-
Notifications
You must be signed in to change notification settings - Fork 3.8k
/
show_trace
325 lines (276 loc) · 21.2 KB
/
show_trace
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
253
254
255
256
257
258
259
260
261
262
263
264
265
266
267
268
269
270
271
272
273
274
275
276
277
278
279
280
281
282
283
284
285
286
287
288
289
290
291
292
293
294
295
296
297
298
299
300
301
302
303
304
305
306
307
308
309
310
311
312
313
314
315
316
317
318
319
320
321
322
323
324
325
# LogicTest: local
# Check SHOW KV TRACE FOR SESSION.
statement ok
SET tracing = on,kv,results; CREATE DATABASE t; SET tracing = off
# Check the KV trace; we need to remove the eventlog entry and
# internal queries since the timestamp is non-deterministic.
query TT
SELECT operation, message FROM [SHOW KV TRACE FOR SESSION]
WHERE message NOT LIKE '%Z/%'
AND operation NOT LIKE 'kv.DistSender: sending partial batch%' -- order of partial batches is not deterministic
AND message NOT SIMILAR TO '%(PushTxn|ResolveIntent|SystemConfigSpan)%'
AND tag NOT LIKE '%intExec=%'
AND tag NOT LIKE '%scExec%'
AND tag NOT LIKE '%IndexBackfiller%'
AND operation != 'dist sender send'
----
flow CPut /NamespaceTable/30/1/0/0/"t"/4/1 -> 53
flow CPut /Table/3/1/53/2/1 -> database:<name:"t" id:53 privileges:<users:<user:"admin" privileges:2 > users:<user:"root" privileges:2 > > >
flow CPut /NamespaceTable/30/1/53/0/"public"/4/1 -> 29
exec stmt rows affected: 0
# More KV operations.
statement ok
SET tracing = on,kv,results; CREATE TABLE t.kv(k INT PRIMARY KEY, v INT); SET tracing = off
query TT
SELECT operation, regexp_replace(regexp_replace(message, 'wall_time:\d+', 'wall_time:...'), 'wall_time:\d+', 'wall_time:...') as message
FROM [SHOW KV TRACE FOR SESSION]
WHERE message NOT LIKE '%Z/%'
AND operation NOT LIKE 'kv.DistSender: sending partial batch%' -- order of partial batches is not deterministic
AND message NOT SIMILAR TO '%(PushTxn|ResolveIntent|SystemConfigSpan)%'
AND tag NOT LIKE '%intExec=%'
AND tag NOT LIKE '%scExec%'
AND tag NOT LIKE '%IndexBackfiller%'
AND operation != 'dist sender send'
----
flow CPut /NamespaceTable/30/1/53/29/"kv"/4/1 -> 54
flow CPut /Table/3/1/54/2/1 -> table:<name:"kv" id:54 parent_id:53 version:1 modification_time:<> columns:<name:"k" id:1 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:false hidden:false > columns:<name:"v" id:2 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:true hidden:false > next_column_id:3 families:<name:"primary" id:0 column_names:"k" column_names:"v" column_ids:1 column_ids:2 default_column_id:2 > next_family_id:1 primary_index:<name:"primary" id:1 unique:true column_names:"k" column_directions:ASC column_ids:1 foreign_key:<table:0 index:0 name:"" validity:Validated shared_prefix_len:0 on_delete:NO_ACTION on_update:NO_ACTION match:SIMPLE > interleave:<> partitioning:<num_columns:0 > type:FORWARD created_explicitly:false > next_index_id:2 privileges:<users:<user:"admin" privileges:2 > users:<user:"root" privileges:2 > > next_mutation_id:1 format_version:3 state:PUBLIC offline_reason:"" view_query:"" drop_time:0 replacement_of:<id:0 time:<> > audit_mode:DISABLED drop_job_id:0 create_query:"" create_as_of_time:<> temporary:false >
exec stmt rows affected: 0
# We avoid using the full trace output, because that would make the
# ensuing trace especially chatty, as it traces the index backfill at
# the end of the implicit transaction. A chatty trace could be OK in
# tests, however the backfill also incur job table traffic which has a
# timestamp index, and we can't use (non-deterministic) timestamp
# values in expected values.
statement ok
SET tracing = on,kv,results; CREATE UNIQUE INDEX woo ON t.kv(v); SET tracing = off
query TT
SELECT operation,
regexp_replace(regexp_replace(regexp_replace(message, 'wall_time:\d+', 'wall_time:...'), 'mutationJobs:<[^>]*>', 'mutationJobs:<...>'), 'wall_time:\d+', 'wall_time:...') as message
FROM [SHOW KV TRACE FOR SESSION]
WHERE message NOT LIKE '%Z/%' AND message NOT LIKE 'querying next range at%'
AND operation NOT LIKE 'kv.DistSender: sending partial batch%' -- order of partial batches is not deterministic
/* since the transactions involved are multi-range, intent resolving is async and
sometimes there's still intents around */
AND message NOT SIMILAR TO '%(PushTxn|ResolveIntent|SystemConfigSpan)%'
AND tag NOT LIKE '%intExec=%'
AND tag NOT LIKE '%scExec%'
AND tag NOT LIKE '%IndexBackfiller%'
AND operation != 'dist sender send'
----
flow Put /Table/3/1/54/2/1 -> table:<name:"kv" id:54 parent_id:53 version:2 modification_time:<> columns:<name:"k" id:1 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:false hidden:false > columns:<name:"v" id:2 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:true hidden:false > next_column_id:3 families:<name:"primary" id:0 column_names:"k" column_names:"v" column_ids:1 column_ids:2 default_column_id:2 > next_family_id:1 primary_index:<name:"primary" id:1 unique:true column_names:"k" column_directions:ASC column_ids:1 foreign_key:<table:0 index:0 name:"" validity:Validated shared_prefix_len:0 on_delete:NO_ACTION on_update:NO_ACTION match:SIMPLE > interleave:<> partitioning:<num_columns:0 > type:FORWARD created_explicitly:false > next_index_id:3 privileges:<users:<user:"admin" privileges:2 > users:<user:"root" privileges:2 > > mutations:<index:<name:"woo" id:2 unique:true column_names:"v" column_directions:ASC column_ids:2 extra_column_ids:1 foreign_key:<table:0 index:0 name:"" validity:Validated shared_prefix_len:0 on_delete:NO_ACTION on_update:NO_ACTION match:SIMPLE > interleave:<> partitioning:<num_columns:0 > type:FORWARD created_explicitly:true > state:DELETE_ONLY direction:ADD mutation_id:1 rollback:false > next_mutation_id:2 format_version:3 state:PUBLIC offline_reason:"" view_query:"" mutationJobs:<...> drop_time:0 replacement_of:<id:0 time:<> > audit_mode:DISABLED drop_job_id:0 create_query:"" create_as_of_time:<wall_time:... > temporary:false >
exec stmt rows affected: 0
statement ok
SET tracing = on,kv,results; INSERT INTO t.kv(k, v) VALUES (1,2); SET tracing = off
query TT
SELECT operation, message FROM [SHOW KV TRACE FOR SESSION]
WHERE operation != 'dist sender send'
----
flow CPut /Table/54/1/1/0 -> /TUPLE/2:2:Int/2
flow InitPut /Table/54/2/2/0 -> /BYTES/0x89
kv.DistSender: sending partial batch r26: sending batch 1 CPut, 1 EndTxn to (n1,s1):1
flow fast path completed
exec stmt rows affected: 1
statement error duplicate key value
SET tracing = on,kv,results; INSERT INTO t.kv(k, v) VALUES (1,2); SET tracing = off
query TT
set tracing=off;
SELECT operation, message FROM [SHOW KV TRACE FOR SESSION]
WHERE operation != 'dist sender send'
----
flow CPut /Table/54/1/1/0 -> /TUPLE/2:2:Int/2
flow InitPut /Table/54/2/2/0 -> /BYTES/0x89
kv.DistSender: sending partial batch r26: sending batch 1 CPut, 1 EndTxn to (n1,s1):1
exec stmt execution failed after 0 rows: duplicate key value (k)=(1) violates unique constraint "primary"
statement error duplicate key value
SET tracing = on,kv,results; INSERT INTO t.kv(k, v) VALUES (2,2); SET tracing = off
query TT
set tracing=off;
SELECT operation, message FROM [SHOW KV TRACE FOR SESSION]
WHERE operation != 'dist sender send'
----
flow CPut /Table/54/1/2/0 -> /TUPLE/2:2:Int/2
flow InitPut /Table/54/2/2/0 -> /BYTES/0x8a
kv.DistSender: sending partial batch r26: sending batch 1 CPut, 1 EndTxn to (n1,s1):1
exec stmt execution failed after 0 rows: duplicate key value (v)=(2) violates unique constraint "woo"
statement ok
SET tracing = on,kv,results; CREATE TABLE t.kv2 AS TABLE t.kv; SET tracing = off
query TT
SELECT operation, regexp_replace(regexp_replace(regexp_replace(message, 'wall_time:\d+', 'wall_time:...'), 'wall_time:\d+', 'wall_time:...'), '\d\d\d\d\d+', '...PK...') as message
FROM [SHOW KV TRACE FOR SESSION]
WHERE message NOT LIKE '%Z/%'
AND operation NOT LIKE 'kv.DistSender: sending partial batch%' -- order of partial batches is not deterministic
AND message NOT SIMILAR TO '%(PushTxn|ResolveIntent|SystemConfigSpan)%'
AND tag NOT LIKE '%intExec=%'
AND tag NOT LIKE '%scExec%'
AND tag NOT LIKE '%IndexBackfiller%'
AND operation != 'dist sender send'
----
table reader Scan /Table/54/{1-2}
flow CPut /NamespaceTable/30/1/53/29/"kv2"/4/1 -> 55
flow CPut /Table/3/1/55/2/1 -> table:<name:"kv2" id:55 parent_id:53 version:1 modification_time:<> columns:<name:"k" id:1 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:true hidden:false > columns:<name:"v" id:2 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:true hidden:false > columns:<name:"rowid" id:3 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:false default_expr:"unique_rowid()" hidden:true > next_column_id:4 families:<name:"primary" id:0 column_names:"k" column_names:"v" column_names:"rowid" column_ids:1 column_ids:2 column_ids:3 default_column_id:0 > next_family_id:1 primary_index:<name:"primary" id:1 unique:true column_names:"rowid" column_directions:ASC column_ids:3 foreign_key:<table:0 index:0 name:"" validity:Validated shared_prefix_len:0 on_delete:NO_ACTION on_update:NO_ACTION match:SIMPLE > interleave:<> partitioning:<num_columns:0 > type:FORWARD created_explicitly:false > next_index_id:2 privileges:<users:<user:"admin" privileges:2 > users:<user:"root" privileges:2 > > next_mutation_id:1 format_version:3 state:ADD offline_reason:"" view_query:"" drop_time:0 replacement_of:<id:0 time:<> > audit_mode:DISABLED drop_job_id:0 create_query:"TABLE t.public.kv" create_as_of_time:<> temporary:false >
exec stmt rows affected: 0
statement ok
SET tracing = on,kv,results; UPDATE t.kv2 SET v = v + 2; SET tracing = off
query TT
SELECT operation, message
FROM [SHOW KV TRACE FOR SESSION]
WHERE message NOT LIKE '%Z/%'
AND message NOT SIMILAR TO '%(PushTxn|ResolveIntent|SystemConfigSpan)%'
AND tag NOT LIKE '%intExec=%'
AND tag NOT LIKE '%scExec%'
AND tag NOT LIKE '%IndexBackfiller%'
AND operation != 'dist sender send'
----
table reader Scan /Table/55/{1-2}
table reader fetched: /kv2/primary/-9222809086901354496/k/v -> /1/2
flow Put /Table/55/1/-9222809086901354496/0 -> /TUPLE/1:1:Int/1/1:2:Int/4
flow fast path completed
exec stmt rows affected: 1
statement ok
SET tracing = on,kv,results; DELETE FROM t.kv2; SET tracing = off
query TT
SELECT operation, message FROM [SHOW KV TRACE FOR SESSION]
WHERE operation != 'dist sender send'
----
flow DelRange /Table/55/1 - /Table/55/2
flow fast path completed
exec stmt rows affected: 1
statement ok
SET tracing = on,kv,results; DROP TABLE t.kv2; SET tracing = off
query TT
SELECT operation,
regexp_replace(regexp_replace(regexp_replace(regexp_replace(message, 'wall_time:\d+', 'wall_time:...'), 'drop_job_id:[1-9]\d*', 'drop_job_id:...'), 'wall_time:\d+', 'wall_time:...'), 'drop_time:\d+', 'drop_time:...') as message
FROM [SHOW KV TRACE FOR SESSION]
WHERE message NOT LIKE '%Z/%' AND message NOT LIKE 'querying next range at%'
AND operation NOT LIKE 'kv.DistSender: sending partial batch%' -- order of partial batches is not deterministic
AND message NOT SIMILAR TO '%(PushTxn|ResolveIntent|SystemConfigSpan)%'
AND tag NOT LIKE '%intExec=%'
AND tag NOT LIKE '%scExec%'
AND tag NOT LIKE '%IndexBackfiller%'
AND operation != 'dist sender send'
----
flow Put /Table/3/1/55/2/1 -> table:<name:"kv2" id:55 parent_id:53 version:3 modification_time:<> columns:<name:"k" id:1 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:true hidden:false > columns:<name:"v" id:2 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:true hidden:false > columns:<name:"rowid" id:3 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:false default_expr:"unique_rowid()" hidden:true > next_column_id:4 families:<name:"primary" id:0 column_names:"k" column_names:"v" column_names:"rowid" column_ids:1 column_ids:2 column_ids:3 default_column_id:0 > next_family_id:1 primary_index:<name:"primary" id:1 unique:true column_names:"rowid" column_directions:ASC column_ids:3 foreign_key:<table:0 index:0 name:"" validity:Validated shared_prefix_len:0 on_delete:NO_ACTION on_update:NO_ACTION match:SIMPLE > interleave:<> partitioning:<num_columns:0 > type:FORWARD created_explicitly:false > next_index_id:2 privileges:<users:<user:"admin" privileges:2 > users:<user:"root" privileges:2 > > next_mutation_id:1 format_version:3 state:DROP offline_reason:"" draining_names:<parent_id:53 name:"kv2" > view_query:"" drop_time:... replacement_of:<id:0 time:<> > audit_mode:DISABLED drop_job_id:... create_query:"TABLE t.public.kv" create_as_of_time:<wall_time:... > temporary:false >
exec stmt rows affected: 0
statement ok
SET tracing = on,kv,results; DELETE FROM t.kv; SET tracing = off
query TT
SELECT operation, message FROM [SHOW KV TRACE FOR SESSION]
WHERE operation != 'dist sender send'
----
table reader Scan /Table/54/{1-2}
table reader fetched: /kv/primary/1/v -> /2
flow Del /Table/54/2/2/0
flow Del /Table/54/1/1/0
kv.DistSender: sending partial batch r26: sending batch 1 Del to (n1,s1):1
flow fast path completed
exec stmt rows affected: 1
statement ok
SET tracing = on,kv,results; DROP INDEX t.kv@woo CASCADE; SET tracing = off
query TT
SELECT operation,
regexp_replace(regexp_replace(regexp_replace(message, 'wall_time:\d+', 'wall_time:...'), 'mutationJobs:<[^>]*>', 'mutationJobs:<...>'), 'wall_time:\d+', 'wall_time:...') as message
FROM [SHOW KV TRACE FOR SESSION]
WHERE message NOT LIKE '%Z/%' AND message NOT LIKE 'querying next range at%'
AND operation NOT LIKE 'kv.DistSender: sending partial batch%' -- order of partial batches is not deterministic
/* since the transactions involved are multi-range, intent resolving is async and
sometimes there's still intents around */
AND message NOT SIMILAR TO '%(PushTxn|ResolveIntent|SystemConfigSpan)%'
AND tag NOT LIKE '%intExec=%'
AND tag NOT LIKE '%scExec%'
AND tag NOT LIKE '%IndexBackfiller%'
AND operation != 'dist sender send'
----
flow Put /Table/3/1/54/2/1 -> table:<name:"kv" id:54 parent_id:53 version:5 modification_time:<> columns:<name:"k" id:1 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:false hidden:false > columns:<name:"v" id:2 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:true hidden:false > next_column_id:3 families:<name:"primary" id:0 column_names:"k" column_names:"v" column_ids:1 column_ids:2 default_column_id:2 > next_family_id:1 primary_index:<name:"primary" id:1 unique:true column_names:"k" column_directions:ASC column_ids:1 foreign_key:<table:0 index:0 name:"" validity:Validated shared_prefix_len:0 on_delete:NO_ACTION on_update:NO_ACTION match:SIMPLE > interleave:<> partitioning:<num_columns:0 > type:FORWARD created_explicitly:false > next_index_id:3 privileges:<users:<user:"admin" privileges:2 > users:<user:"root" privileges:2 > > mutations:<index:<name:"woo" id:2 unique:true column_names:"v" column_directions:ASC column_ids:2 extra_column_ids:1 foreign_key:<table:0 index:0 name:"" validity:Validated shared_prefix_len:0 on_delete:NO_ACTION on_update:NO_ACTION match:SIMPLE > interleave:<> partitioning:<num_columns:0 > type:FORWARD created_explicitly:true > state:DELETE_AND_WRITE_ONLY direction:DROP mutation_id:2 rollback:false > next_mutation_id:3 format_version:3 state:PUBLIC offline_reason:"" view_query:"" mutationJobs:<...> drop_time:0 replacement_of:<id:0 time:<> > audit_mode:DISABLED drop_job_id:0 create_query:"" create_as_of_time:<wall_time:... > temporary:false >
exec stmt rows affected: 0
statement ok
SET tracing = on,kv,results; DROP TABLE t.kv; SET tracing = off
query TT
SELECT operation, regexp_replace(regexp_replace(regexp_replace(regexp_replace(message, 'wall_time:\d+', 'wall_time:...'), 'job_id:[1-9]\d*', 'job_id:...', 'g'), 'wall_time:\d+', 'wall_time:...'), 'drop_time:\d+', 'drop_time:...', 'g') as message
FROM [SHOW KV TRACE FOR SESSION]
WHERE message NOT LIKE '%Z/%' AND message NOT LIKE 'querying next range at%'
AND operation NOT LIKE 'kv.DistSender: sending partial batch%' -- order of partial batches is not deterministic
AND message NOT SIMILAR TO '%(PushTxn|ResolveIntent|SystemConfigSpan)%'
AND tag NOT LIKE '%intExec=%'
AND tag NOT LIKE '%scExec%'
AND tag NOT LIKE '%IndexBackfiller%'
AND operation != 'dist sender send'
----
flow Put /Table/3/1/54/2/1 -> table:<name:"kv" id:54 parent_id:53 version:8 modification_time:<> columns:<name:"k" id:1 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:false hidden:false > columns:<name:"v" id:2 type:<InternalType:<family:IntFamily width:64 precision:0 locale:"" visible_type:0 oid:20 > > nullable:true hidden:false > next_column_id:3 families:<name:"primary" id:0 column_names:"k" column_names:"v" column_ids:1 column_ids:2 default_column_id:2 > next_family_id:1 primary_index:<name:"primary" id:1 unique:true column_names:"k" column_directions:ASC column_ids:1 foreign_key:<table:0 index:0 name:"" validity:Validated shared_prefix_len:0 on_delete:NO_ACTION on_update:NO_ACTION match:SIMPLE > interleave:<> partitioning:<num_columns:0 > type:FORWARD created_explicitly:false > next_index_id:3 privileges:<users:<user:"admin" privileges:2 > users:<user:"root" privileges:2 > > next_mutation_id:3 format_version:3 state:DROP offline_reason:"" draining_names:<parent_id:53 name:"kv" > view_query:"" drop_time:... replacement_of:<id:0 time:<> > audit_mode:DISABLED drop_job_id:... gc_mutations:<index_id:2 drop_time:... job_id:... > create_query:"" create_as_of_time:<wall_time:... > temporary:false >
exec stmt rows affected: 0
# Check that session tracing does not inhibit the fast path for inserts &
# friends (the path resulting in 1PC transactions).
subtest autocommit
statement ok
CREATE TABLE t.kv3(k INT PRIMARY KEY, v INT)
statement ok
SET tracing = on; INSERT INTO t.kv3 (k, v) VALUES (1,1); SET tracing = off
# We look for rows containing an EndTxn as proof that the
# insertNode is committing the txn.
query T
SELECT message FROM [SHOW TRACE FOR SESSION] WHERE message LIKE e'%1 CPut, 1 EndTxn%' AND message NOT LIKE e'%proposing command%'
----
r27: sending batch 1 CPut, 1 EndTxn to (n1,s1):1
1 CPut, 1 EndTxn
## TODO(tschottdorf): re-enable
# statement ok
# CREATE TABLE t.enginestats(k INT PRIMARY KEY, v INT)
#
# statement ok
# SHOW TRACE FOR SELECT * FROM t.enginestats
#
# query T
# SELECT message FROM [ SHOW TRACE FOR SESSION ] WHERE message LIKE '%InternalDelete%'
# ----
# engine stats: {InternalDeleteSkippedCount:0 TimeBoundNumSSTs:0}
# Check that we can run set tracing regardless of the current tracing state.
# This is convenient; sometimes it's unclear, for example, if you previously
# stopped tracing or not, so issuing a set tracing=off should just work.
subtest idempotent
statement ok
SET tracing = on; SET tracing = on;
statement ok
SET tracing = off; SET tracing = off;
# Check that we can run set tracing in the aborted state (this is implemented by
# making set tracing an ObserverStmt). This is very convenient for clients that
# start tracing, then might get an error, then want to stop tracing.
subtest aborted_txn
query error pq: foo
BEGIN; SELECT crdb_internal.force_error('', 'foo')
statement ok
SET tracing = off
statement ok
ROLLBACK
subtest replica
statement ok
CREATE TABLE t (a INT PRIMARY KEY)
statement ok
SET tracing = on; SELECT * FROM t; SET tracing = off
query III colnames
SELECT DISTINCT node_id, store_id, replica_id
FROM [SHOW EXPERIMENTAL_REPLICA TRACE FOR SESSION]
----
node_id store_id replica_id
1 1 26
1 1 6
1 1 27
subtest system_table_lookup
# We use AOST to bypass the table cache.
statement ok
SET tracing = on,kv; SELECT * FROM system.eventlog AS OF SYSTEM TIME '-1us'; SET tracing = off
query TT
SELECT operation, regexp_replace(regexp_replace(regexp_replace(message, 'job_id:[1-9]\d*', 'job_id:...', 'g'), 'wall_time:\d+', 'wall_time:...'), 'drop_time:\d+', 'drop_time:...', 'g') as message
FROM [SHOW KV TRACE FOR SESSION]
WHERE (message LIKE 'querying next range%' OR message LIKE '%batch%')
AND message NOT LIKE '%SystemConfigSpan%'
AND message NOT LIKE '%PushTxn%'
----
dist sender send querying next range at /NamespaceTable/30/1/0/0/"test"/4/1
dist sender send r26: sending batch 1 Get to (n1,s1):1
dist sender send querying next range at /NamespaceTable/30/1/52/0/"system"/4/1
dist sender send r26: sending batch 1 Get to (n1,s1):1
dist sender send querying next range at /Table/3/1/12/2/1
dist sender send r6: sending batch 1 Get to (n1,s1):1
dist sender send querying next range at /Table/3/1/1/2/1
dist sender send r6: sending batch 1 Get to (n1,s1):1
dist sender send querying next range at /Table/12/1
dist sender send r8: sending batch 1 Scan to (n1,s1):1