cockroach-teamcity
commented
1 year ago ccl/changefeedccl.TestChangefeedTopicNames failed with artifacts on release-23.1 @ cfbd751ae4c82e2c4b5314376cd6e6c29cc30aea:
I230409 13:54:47.791608 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 836 ={"Timestamp":1681048487770396800,"EventType":"create_table","Statement":"CREATE TABLE d.public.table82 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":187,"TableName":"d.public.table82"}
I230409 13:54:47.820955 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 837 ={"Timestamp":1681048487811557565,"EventType":"create_table","Statement":"CREATE TABLE d.public.table83 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":188,"TableName":"d.public.table83"}
I230409 13:54:47.842088 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 838 ={"Timestamp":1681048487827916508,"EventType":"create_table","Statement":"CREATE TABLE d.public.table84 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":189,"TableName":"d.public.table84"}
I230409 13:54:47.858896 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 839 ={"Timestamp":1681048487850291145,"EventType":"create_table","Statement":"CREATE TABLE d.public.\"tab%vle85\" (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":190,"TableName":"d.public.\"tab%vle85\""}
I230409 13:54:47.884558 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 840 ={"Timestamp":1681048487866752323,"EventType":"create_table","Statement":"CREATE TABLE d.public.\"tablE86\" (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":191,"TableName":"d.public.\"tablE86\""}
I230409 13:54:47.907716 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 841 ={"Timestamp":1681048487894705407,"EventType":"create_table","Statement":"CREATE TABLE d.public.table87 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":192,"TableName":"d.public.table87"}
I230409 13:54:47.960327 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 842 ={"Timestamp":1681048487946782650,"EventType":"create_table","Statement":"CREATE TABLE d.public.table88 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":193,"TableName":"d.public.table88"}
I230409 13:54:47.988407 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 843 ={"Timestamp":1681048487972025492,"EventType":"create_table","Statement":"CREATE TABLE d.public.table89 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":194,"TableName":"d.public.table89"}
I230409 13:54:48.013613 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 844 ={"Timestamp":1681048487995989045,"EventType":"create_table","Statement":"CREATE TABLE d.public.\"\"\"ta bl͛e90\" (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":195,"TableName":"d.public.\"\"\"ta bl͛e90\""}
I230409 13:54:48.037710 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 845 ={"Timestamp":1681048488027876966,"EventType":"create_table","Statement":"CREATE TABLE d.public.\"taBle91\" (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":196,"TableName":"d.public.\"taBle91\""}
I230409 13:54:48.056154 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 846 ={"Timestamp":1681048488046211611,"EventType":"create_table","Statement":"CREATE TABLE d.public.\"Table92\" (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":197,"TableName":"d.public.\"Table92\""}
I230409 13:54:48.080180 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 847 ={"Timestamp":1681048488071431955,"EventType":"create_table","Statement":"CREATE TABLE d.public.table93 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":198,"TableName":"d.public.table93"}
I230409 13:54:48.096254 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 848 ={"Timestamp":1681048488087600393,"EventType":"create_table","Statement":"CREATE TABLE d.public.table94 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":199,"TableName":"d.public.table94"}
I230409 13:54:48.118076 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 849 ={"Timestamp":1681048488104774032,"EventType":"create_table","Statement":"CREATE TABLE d.public.\"table%q95\" (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":200,"TableName":"d.public.\"table%q95\""}
I230409 13:54:48.141136 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 850 ={"Timestamp":1681048488125067435,"EventType":"create_table","Statement":"CREATE TABLE d.public.\"tä́Ble96\" (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":201,"TableName":"d.public.\"tä́Ble96\""}
I230409 13:54:48.158271 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 851 ={"Timestamp":1681048488148960251,"EventType":"create_table","Statement":"CREATE TABLE d.public.table97 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":202,"TableName":"d.public.table97"}
I230409 13:54:48.180844 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 852 ={"Timestamp":1681048488164669847,"EventType":"create_table","Statement":"CREATE TABLE d.public.table98 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":203,"TableName":"d.public.table98"}
I230409 13:54:48.197202 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 853 ={"Timestamp":1681048488188539526,"EventType":"create_table","Statement":"CREATE TABLE d.public.table99 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":204,"TableName":"d.public.table99"}
I230409 13:54:48.212110 101343 5@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 854 ={"Timestamp":1681048488203921798,"EventType":"create_table","Statement":"CREATE TABLE d.public.table100 (a INT8 PRIMARY KEY)","Tag":"CREATE TABLE","User":"root","DescriptorID":205,"TableName":"d.public.table100"}
I230409 13:54:48.220133 94254 ccl/changefeedccl/testfeed_test.go:795 [-] 855 Starting feed job: "CREATE CHANGEFEED FOR TABLE table1, TABLE table2, TABLE \"table\n3\", TABLE t😒able4, TABLE \"tabLe5\", TABLE table6, TABLE tab😭l̤e7, TABLE \" table8\", TABLE \"t😧abLe9\", TABLE table10, TABLE \"tab\vl😄e11\", TABLE table12, TABLE \"tab%vle13\", TABLE table14, TABLE \"table 😑15\", TABLE \"tabl e16\", TABLE \"table%v17\", TABLE \"tab&l e18\", TABLE table19, TABLE table20, TABLE table21, TABLE tảble22, TABLE t😻able23, TABLE \" tabl%ve24\", TABLE table25, TABLE table26, TABLE table27, TABLE \"t\\vable28\", TABLE tabl_e29, TABLE table30, TABLE table31, TABLE table32, TABLE \"%qta\"\"ble33\", TABLE table34, TABLE table35, TABLE \"ta}ble36\", TABLE table37, TABLE table38, TABLE table39, TABLE tab😳le40, TABLE table41, TABLE \"Table42\", TABLE table43, TABLE \"tabl e44\", TABLE \"t?able45\", TABLE \"t\\\\xd0Able46\", TABLE table47, TABLE \"\ftable48\", TABLE \"t\nable49\", TABLE tabͩle50, TABLE \"table'51\", TABLE table52, TABLE \"tab,le53\", TABLE table54, TABLE table55, TABLE \"\rtab😥le56\", TABLE table57, TABLE table58, TABLE table59, TABLE \"t\\\\U00038F08able60\", TABLE 😟table61, TABLE table62, TABLE table63, TABLE table64, TABLE \"t a)ble65\", TABLE \"tab\tle66\", TABLE \"table\\\\xc167\", TABLE \"table%p68\", TABLE table69, TABLE \"Table70\", TABLE table̥71, TABLE \"'table72\", TABLE \"\"\"tabl{e73\", TABLE table74, TABLE table75, TABLE \"tab le76\", TABLE table77, TABLE \"\\\\x03ta\"\"ble78\", TABLE \" tab\"\"le79\", TABLE table80, TABLE table͐81, TABLE table82, TABLE table83, TABLE table84, TABLE \"tab%vle85\", TABLE \"tablE86\", TABLE table87, TABLE table88, TABLE table89, TABLE \"\"\"ta bl͛e90\", TABLE \"taBle91\", TABLE \"Table92\", TABLE table93, TABLE table94, TABLE \"table%q95\", TABLE \"tä́Ble96\", TABLE table97, TABLE table98, TABLE table99, TABLE table100 INTO 'gcpubsub://testfeed?region=testfeedRegion'"
I230409 13:54:48.220800 101343 sql/schemachanger/scbuild/build.go:45 [T10,nsql1,client=127.0.0.1:53394,user=root] 856 building declarative schema change targets for CREATE CHANGEFEED
I230409 13:54:48.221325 108468 1@ccl/changefeedccl/changefeed_stmt.go:965 [T10,nsql1,client=127.0.0.1:53394,user=root] 857 changefeed planning to connect to destination gcpubsub://testfeed?region=testfeedRegion
I230409 13:54:48.240732 108468 12@util/log/event_log.go:32 [T10,nsql1,client=127.0.0.1:53394,user=root] 858 ={"Timestamp":1681048488240726667,"EventType":"changefeed_failed","FailureType":"user_input"}
changefeed_test.go:8465: failed to start feed for job 0: pq: opening client: google: could not find default credentials. See https://developers.google.com/accounts/docs/application-default-credentials for more information.
I230409 13:54:48.242613 98094 sql/stats/automatic_stats.go:550 [T10,nsql1] 859 quiescing auto stats refresher
I230409 13:54:48.243478 97838 server/start_listen.go:103 [T10,nsql1] 860 server shutting down: instructing cmux to stop accepting
W230409 13:54:48.243673 97875 ccl/kvccl/kvtenantccl/setting_overrides.go:46 [T10,nsql1,tenant-connector] 861 error consuming TenantSettings RPC: recv msg error: grpc: context canceled [code 1/Canceled]
W230409 13:54:48.243780 97792 sql/sqlliveness/slinstance/slinstance.go:334 [T10,nsql1] 862 exiting heartbeat loop
W230409 13:54:48.243802 97792 sql/sqlliveness/slinstance/slinstance.go:321 [T10,nsql1] 863 exiting heartbeat loop with error: node unavailable; try another peer
I230409 13:54:48.244678 98777 jobs/registry.go:1538 [T10,nsql1] 864 AUTO SPAN CONFIG RECONCILIATION job 855172984600363009: stepping through state succeeded
E230409 13:54:48.244803 98777 jobs/registry.go:943 [-] 865 error getting live session: node unavailable; try another peer
I230409 13:54:48.245116 97877 rpc/context.go:2302 [T10,nsql1,rnode=63,raddr=127.0.0.1:40485,class=system,rpc] 866 connection heartbeat loop ended with err: <nil>
E230409 13:54:48.245172 98102 jobs/registry.go:1015 [T10,nsql1] 867 error claiming jobs: could not query jobs table: claim-jobs: failed to read query result: query execution canceled
I230409 13:54:48.245242 98034 rpc/context.go:2302 [T10,nsql1,rnode=1,raddr=127.0.0.1:40485,class=default,rpc] 868 connection heartbeat loop ended with err: <nil>
W230409 13:54:48.245503 97874 ccl/kvccl/kvtenantccl/connector.go:249 [T10,nsql1,tenant-connector] 869 error consuming GossipSubscription RPC: recv msg error: grpc: context canceled [code 1/Canceled]
W230409 13:54:48.247976 100961 jobs/adopt.go:518 [-] 870 could not clear job claim: clear-job-claim: tenant-side-limiter pool closed: shutting down
W230409 13:54:48.260336 94254 server/server_sql.go:1666 [T10,nsql1] 871 server shutdown without a prior graceful drain
I230409 13:54:48.260649 94910 sql/stats/automatic_stats.go:550 [T1,n1] 872 quiescing auto stats refresher
W230409 13:54:48.260995 106268 kv/kvserver/intentresolver/intent_resolver.go:826 [-] 873 failed to gc transaction record: could not GC completed transaction anchored at /Local/Range/Tenant/10/RangeDescriptor: node unavailable; try another peer
I230409 13:54:48.266151 94555 server/start_listen.go:103 [T1,n1] 874 server shutting down: instructing cmux to stop accepting
W230409 13:54:48.266261 94750 sql/sqlliveness/slinstance/slinstance.go:334 [T1,n1] 875 exiting heartbeat loop
W230409 13:54:48.266317 94750 sql/sqlliveness/slinstance/slinstance.go:321 [T1,n1] 876 exiting heartbeat loop with error: node unavailable; try another peer
I230409 13:54:48.266340 95171 jobs/registry.go:1538 [T1,n1] 877 AUTO SPAN CONFIG RECONCILIATION job 855172974373502977: stepping through state succeeded
I230409 13:54:48.266308 96318 jobs/registry.go:1538 [T1,n1] 879 KEY VISUALIZER job 100: stepping through state succeeded
I230409 13:54:48.266404 96115 rpc/context.go:2302 [T1,n1,rnode=1,raddr=127.0.0.1:40485,class=default,rpc] 880 connection heartbeat loop ended with err: <nil>
E230409 13:54:48.266353 94750 server/server_sql.go:508 [T1,n1] 878 failed to run update of instance with new session ID: node unavailable; try another peer
E230409 13:54:48.266480 96514 jobs/registry.go:943 [T1,n1] 881 error getting live session: node unavailable; try another peer
W230409 13:54:48.270662 96660 jobs/adopt.go:518 [T1,n1] 882 could not clear job claim: clear-job-claim: failed to send RPC: sending to all replicas failed; last error: ba: Scan [/Table/15/1/102,/Table/15/1/103), [txn: 67c2750a], [can-forward-ts] RPC error: node unavailable; try another peer
W230409 13:54:48.272411 94254 server/server_sql.go:1666 [T1,n1] 883 server shutdown without a prior graceful drain
--- FAIL: TestChangefeedTopicNames/pubsub (7.82s)Help
See also: [How To Investigate a Go Test Failure \(internal\)](https://cockroachlabs.atlassian.net/l/c/HgfXfJgM)
ccl/changefeedccl.TestChangefeedTopicNames failed with artifacts on release-23.1 @ cfbd751ae4c82e2c4b5314376cd6e6c29cc30aea:
Help
See also: [How To Investigate a Go Test Failure \(internal\)](https://cockroachlabs.atlassian.net/l/c/HgfXfJgM)
/cc @cockroachdb/cdc
This test on roachdash | Improve this report!
Jira issue: CRDB-26753
Epic CRDB-11732