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

roachtest: cdc/sink-chaos/rangefeed=true failed #43735

Closed
cockroach-teamcity opened this issue Jan 6, 2020 · 11 comments · Fixed by #46391
Closed

roachtest: cdc/sink-chaos/rangefeed=true failed #43735

cockroach-teamcity opened this issue Jan 6, 2020 · 11 comments · Fixed by #46391
Assignees
Labels
branch-master Failures and bugs on the master branch. C-test-failure Broken test (automatically or manually discovered). O-roachtest O-robot Originated from a bot.
Milestone

Comments

@cockroach-teamcity
Copy link
Member

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@f180501b7c2ccb946d7f7c8944d5509b87cb0394:

The test failed on branch=master, cloud=gce:
test artifacts and logs in: /home/agent/work/.go/src/github.com/cockroachdb/cockroach/artifacts/20200106-1674751/cdc/sink-chaos/rangefeed=true/run_1
	cluster.go:1809,cdc.go:791,cdc.go:136,cluster.go:2168,errgroup.go:57: error with attached stack trace:
		    main.execCmd
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:399
		    main.(*cluster).RunL
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:1843
		    main.(*cluster).Run
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:1807
		    main.(*tpccWorkload).run
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:791
		    main.cdcBasicTest.func1
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:136
		    main.(*monitor).Go.func1
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2168
		    github.com/cockroachdb/cockroach/vendor/golang.org/x/sync/errgroup.(*Group).Go.func1
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/vendor/golang.org/x/sync/errgroup/errgroup.go:57
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - error with embedded safe details: %s returned:
		    stderr:
		    %s
		    stdout:
		    %s
		    -- arg 1: <string>
		    -- arg 2: <string>
		    -- arg 3: <string>
		  - /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod run teamcity-1674751-1578297236-23-n4cpu16:4 -- ./workload run tpcc --warehouses=100 --duration=30m --tolerate-errors {pgurl:1-3}  returned:
		    stderr:
		    
		    stdout:
		    .0            0.0      0.0      0.0      0.0      0.0 newOrder
		       20.0s      387            1.0            2.5   1275.1   1275.1   1275.1   1275.1 orderStatus
		       20.0s      387           96.0           14.1  16643.0  16643.0  16643.0  17179.9 payment
		       20.0s      387            0.0            1.7      0.0      0.0      0.0      0.0 stockLevel
		    E200106 08:38:12.843935 1 workload/cli/run.go:444  error in payment: dial tcp 10.128.0.32:26257: connect: connection refused
		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		       21.0s      413            0.0            2.4      0.0      0.0      0.0      0.0 delivery
		       21.0s      413            0.0            0.0      0.0      0.0      0.0      0.0 newOrder
		       21.0s      413            0.0            2.4      0.0      0.0      0.0      0.0 orderStatus
		       21.0s      413           10.0           14.0  18253.6  18253.6  18253.6  18253.6 payment
		       21.0s      413            0.0            1.6      0.0      0.0      0.0      0.0 stockLevel:
		  - context canceled
Repro

Artifacts: /cdc/sink-chaos/rangefeed=true

make stressrace TESTS=cdc/sink-chaos/rangefeed=true PKG=./pkg/roachtest TESTTIMEOUT=5m STRESSFLAGS='-timeout 5m' 2>&1

powered by pkg/cmd/internal/issues

@cockroach-teamcity cockroach-teamcity added C-test-failure Broken test (automatically or manually discovered). O-roachtest O-robot Originated from a bot. labels Jan 6, 2020
@cockroach-teamcity cockroach-teamcity added this to the 20.1 milestone Jan 6, 2020
@tbg tbg added the branch-master Failures and bugs on the master branch. label Jan 22, 2020
@cockroach-teamcity
Copy link
Member Author

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@2739821b911d777fa2a927295d699b559360a802:

		    main.(*cluster).Run
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:1933
		    main.(*tpccWorkload).install
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:787
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:129
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:741
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - error with embedded safe details: output in %s
		    -- arg 1: <string>
		  - output in run_083018.027_n4_workload_fixtures_load_tpcc:
		  - error with attached stack trace:
		    main.execCmd
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:406
		    main.(*cluster).RunL
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2019
		    main.(*cluster).RunE
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2000
		    main.(*cluster).Run
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:1933
		    main.(*tpccWorkload).install
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:787
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:129
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:741
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - error with embedded safe details: %s returned:
		    stderr:
		    %s
		    stdout:
		    %s
		    -- arg 1: <string>
		    -- arg 2: <string>
		    -- arg 3: <string>
		  - /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod run teamcity-1747374-1581581035-24-n4cpu16:4 -- ./workload fixtures load tpcc --warehouses=100 --checks=false {pgurl:3} returned:
		    stderr:
		    I200213 08:30:19.954577 1 ccl/workloadccl/cliccl/fixtures.go:279  starting restore of 9 tables
		    Error: restoring fixture: sql: expected 6 destination arguments in Scan, not 7
		    Error:  exit status 1
		    
		    stdout::
		  - exit status 1

More

Artifacts: /cdc/sink-chaos/rangefeed=true

See this test on roachdash
powered by pkg/cmd/internal/issues

@irfansharif
Copy link
Contributor

Most recent failure was fixed in #45078.

@cockroach-teamcity
Copy link
Member Author

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@c473f40078994551cebcbe00fdbf1fa388957658:

		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		     1601.0s        0            1.0            2.1     35.7     35.7     35.7     35.7 delivery
		     1601.0s        0           27.0           20.9     22.0     23.1     23.1     23.1 newOrder
		     1601.0s        0            3.0            2.1      4.1      5.0      5.0      5.0 orderStatus
		     1601.0s        0           17.0           21.0     11.5     13.6     14.2     14.2 payment
		     1601.0s        0            6.0            2.1     10.5     12.6     12.6     12.6 stockLevel
		     1602.0s        0            3.0            2.1     28.3     35.7     35.7     35.7 delivery
		     1602.0s        0           22.0           20.9     22.0     29.4     31.5     31.5 newOrder
		     1602.0s        0            2.0            2.1      4.1      6.3      6.3      6.3 orderStatus
		     1602.0s        0           25.0           21.0     12.1     13.6     18.9     18.9 payment
		     1602.0s        0            2.0            2.1     11.0     11.5     11.5     11.5 stockLevel
		     1603.0s       20            1.0            2.1     25.2     25.2     25.2     25.2 delivery
		     1603.0s       20            7.0           20.9   1946.2  10737.4  10737.4  10737.4 newOrder
		     1603.0s       20            2.0            2.1      4.2      5.2      5.2      5.2 orderStatus
		     1603.0s       20            0.0           21.0      0.0      0.0      0.0      0.0 payment
		     1603.0s       20            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel
		     1604.0s       38            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		     1604.0s       38            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		     1604.0s       38            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		     1604.0s       38            0.0           21.0      0.0      0.0      0.0      0.0 payment
		     1604.0s       38            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel
		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		     1605.0s       62            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		     1605.0s       62            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		     1605.0s       62            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		     1605.0s       62            0.0           21.0      0.0      0.0      0.0      0.0 payment
		     1605.0s       62            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel:
		  - context canceled

	cluster.go:2368,cdc.go:224,cdc.go:574,test_runner.go:741: error with attached stack trace:
		    main.(*monitor).WaitE
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2356
		    main.(*monitor).Wait
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2364
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:224
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:741
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor failure:
		  - unexpected node event: 3: dead

	cluster.go:1410,context.go:135,cluster.go:1399,test_runner.go:778: dead node detection: /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod monitor teamcity-1796240-1583738442-24-n4cpu16 --oneshot --ignore-empty-nodes: exit status 1 4: skipped
		1: 5193
		3: dead
		2: 5109
		Error:  3: dead

More

Artifacts: /cdc/sink-chaos/rangefeed=true

See this test on roachdash
powered by pkg/cmd/internal/issues

@cockroach-teamcity
Copy link
Member Author

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@793a9200c16693aff32aa6a4dd9d8bbcbddb30aa:

		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		      781.0s        0            2.0            2.1     35.7     39.8     39.8     39.8 delivery
		      781.0s        0           22.0           20.9     22.0     27.3     27.3     27.3 newOrder
		      781.0s        0            2.0            2.1      4.5      5.2      5.2      5.2 orderStatus
		      781.0s        0           21.0           21.0     12.1     15.7     17.8     17.8 payment
		      781.0s        0            4.0            2.1     11.0     12.6     12.6     12.6 stockLevel
		      782.0s        0            3.0            2.1     33.6     41.9     41.9     41.9 delivery
		      782.0s        0           22.0           20.9     23.1  12348.0  21474.8  21474.8 newOrder
		      782.0s        0            1.0            2.1      8.1      8.1      8.1      8.1 orderStatus
		      782.0s        0           13.0           21.0     13.1     15.2     16.3     16.3 payment
		      782.0s        0            1.0            2.1      9.4      9.4      9.4      9.4 stockLevel
		      783.0s       16            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		      783.0s       16            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		      783.0s       16            1.0            2.1      5.0      5.0      5.0      5.0 orderStatus
		      783.0s       16            0.0           21.0      0.0      0.0      0.0      0.0 payment
		      783.0s       16            1.0            2.1      7.3      7.3      7.3      7.3 stockLevel
		      784.0s       33            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		      784.0s       33            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		      784.0s       33            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		      784.0s       33            0.0           21.0      0.0      0.0      0.0      0.0 payment
		      784.0s       33            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel
		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		      785.0s       50            0.0            2.0      0.0      0.0      0.0      0.0 delivery
		      785.0s       50            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		      785.0s       50            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		      785.0s       50            0.0           21.0      0.0      0.0      0.0      0.0 payment
		      785.0s       50            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel:
		  - context canceled

	cluster.go:2368,cdc.go:224,cdc.go:574,test_runner.go:747: error with attached stack trace:
		    main.(*monitor).WaitE
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2356
		    main.(*monitor).Wait
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2364
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:224
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:747
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor failure:
		  - unexpected node event: 2: dead

	cluster.go:1410,context.go:135,cluster.go:1399,test_runner.go:801: dead node detection: /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod monitor teamcity-1804460-1583998462-25-n4cpu16 --oneshot --ignore-empty-nodes: exit status 1 4: skipped
		2: dead
		3: 5068
		1: 4671
		Error:  2: dead

More

Artifacts: /cdc/sink-chaos/rangefeed=true

See this test on roachdash
powered by pkg/cmd/internal/issues

@cockroach-teamcity
Copy link
Member Author

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@69dc87d68addedf2fabfb2b14c098cfb35b5f3d0:

		     1019.0s        0            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel
		     1020.0s        0            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		     1020.0s        0           19.0           20.8     22.0     25.2     26.2     26.2 newOrder
		     1020.0s        0            3.0            2.1      5.5      5.5      5.5      5.5 orderStatus
		     1020.0s        0           22.0           21.1     13.1     16.8     17.8     17.8 payment
		     1020.0s        0            1.0            2.1     11.5     11.5     11.5     11.5 stockLevel
		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		     1021.0s        7            1.0            2.1     35.7     35.7     35.7     35.7 delivery
		     1021.0s        7           16.0           20.8     23.1   4563.4  21474.8  21474.8 newOrder
		     1021.0s        7            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		     1021.0s        7           13.0           21.1     14.7     18.9     19.9     19.9 payment
		     1021.0s        7            1.0            2.1      8.4      8.4      8.4      8.4 stockLevel
		     1022.0s       29            1.0            2.1     32.5     32.5     32.5     32.5 delivery
		     1022.0s       29            0.0           20.8      0.0      0.0      0.0      0.0 newOrder
		     1022.0s       29            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		     1022.0s       29            0.0           21.1      0.0      0.0      0.0      0.0 payment
		     1022.0s       29            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel
		     1023.0s       41            1.0            2.1     58.7     58.7     58.7     58.7 delivery
		     1023.0s       41            0.0           20.8      0.0      0.0      0.0      0.0 newOrder
		     1023.0s       41            1.0            2.1      5.2      5.2      5.2      5.2 orderStatus
		     1023.0s       41            0.0           21.1      0.0      0.0      0.0      0.0 payment
		     1023.0s       41            1.0            2.1      9.4      9.4      9.4      9.4 stockLevel
		     1024.0s       65            1.0            2.1     29.4     29.4     29.4     29.4 delivery
		     1024.0s       65            0.0           20.8      0.0      0.0      0.0      0.0 newOrder
		     1024.0s       65            1.0            2.1      5.2      5.2      5.2      5.2 orderStatus
		     1024.0s       65            0.0           21.0      0.0      0.0      0.0      0.0 payment
		     1024.0s       65            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel:
		  - context canceled

	cluster.go:2368,cdc.go:224,cdc.go:574,test_runner.go:753: error with attached stack trace:
		    main.(*monitor).WaitE
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2356
		    main.(*monitor).Wait
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2364
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:224
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:753
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor failure:
		  - unexpected node event: 2: dead

	cluster.go:1410,context.go:135,cluster.go:1399,test_runner.go:807: dead node detection: /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod monitor teamcity-1806460-1584083876-24-n4cpu16 --oneshot --ignore-empty-nodes: exit status 1 4: skipped
		3: 5047
		2: dead
		1: 5309
		Error:  2: dead

More

Artifacts: /cdc/sink-chaos/rangefeed=true

See this test on roachdash
powered by pkg/cmd/internal/issues

@cockroach-teamcity
Copy link
Member Author

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@f5585e933a097b53242d8d5800127b821a9a4d41:

		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		     1249.0s        0            4.0            2.1     33.6     41.9     41.9     41.9 delivery
		     1249.0s        0           20.0           20.9     21.0     22.0     23.1     23.1 newOrder
		     1249.0s        0            5.0            2.1      4.7      5.2      5.2      5.2 orderStatus
		     1249.0s        0           29.0           21.0     11.5     13.6     13.6     13.6 payment
		     1249.0s        0            1.0            2.1      8.9      8.9      8.9      8.9 stockLevel
		     1250.0s        0            1.0            2.1     39.8     39.8     39.8     39.8 delivery
		     1250.0s        0           25.0           20.9     21.0     25.2     27.3     27.3 newOrder
		     1250.0s        0            1.0            2.1      5.0      5.0      5.0      5.0 orderStatus
		     1250.0s        0           19.0           21.0     11.5     14.2     15.2     15.2 payment
		     1250.0s        0            2.0            2.1      8.1     11.5     11.5     11.5 stockLevel
		     1251.0s       12            1.0            2.1     37.7     37.7     37.7     37.7 delivery
		     1251.0s       12            4.0           20.9   3489.7  10737.4  10737.4  10737.4 newOrder
		     1251.0s       12            1.0            2.1      5.5      5.5      5.5      5.5 orderStatus
		     1251.0s       12            1.0           21.0     12.1     12.1     12.1     12.1 payment
		     1251.0s       12            1.0            2.1     10.0     10.0     10.0     10.0 stockLevel
		     1252.0s       24            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		     1252.0s       24            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		     1252.0s       24            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		     1252.0s       24            0.0           21.0      0.0      0.0      0.0      0.0 payment
		     1252.0s       24            1.0            2.1      8.4      8.4      8.4      8.4 stockLevel
		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		     1253.0s       45            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		     1253.0s       45            0.0           20.8      0.0      0.0      0.0      0.0 newOrder
		     1253.0s       45            3.0            2.1      4.2      4.7      4.7      4.7 orderStatus
		     1253.0s       45            0.0           20.9      0.0      0.0      0.0      0.0 payment
		     1253.0s       45            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel:
		  - context canceled

	cluster.go:2368,cdc.go:224,cdc.go:574,test_runner.go:753: error with attached stack trace:
		    main.(*monitor).WaitE
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2356
		    main.(*monitor).Wait
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2364
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:224
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:753
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor failure:
		  - unexpected node event: 2: dead

	cluster.go:1410,context.go:135,cluster.go:1399,test_runner.go:807: dead node detection: /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod monitor teamcity-1807675-1584170966-25-n4cpu16 --oneshot --ignore-empty-nodes: exit status 1 4: skipped
		2: dead
		3: 4624
		1: 4520
		Error:  2: dead

More

Artifacts: /cdc/sink-chaos/rangefeed=true

See this test on roachdash
powered by pkg/cmd/internal/issues

@cockroach-teamcity
Copy link
Member Author

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@5a3d0c9539a671f0e55b680d3021b18dde9d190d:

		      293.0s        0           17.0           20.9     26.2  20401.1  21474.8  21474.8 newOrder
		      293.0s        0            3.0            2.1      5.5     10.0     10.0     10.0 orderStatus
		      293.0s        0           28.0           21.5     12.6     18.9     22.0     22.0 payment
		      293.0s        0            0.0            2.2      0.0      0.0      0.0      0.0 stockLevel
		      294.0s       21            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		      294.0s       21            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		      294.0s       21            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		      294.0s       21            0.0           21.4      0.0      0.0      0.0      0.0 payment
		      294.0s       21            0.0            2.2      0.0      0.0      0.0      0.0 stockLevel
		      295.0s       39            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		      295.0s       39            0.0           20.8      0.0      0.0      0.0      0.0 newOrder
		      295.0s       39            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		      295.0s       39            0.0           21.3      0.0      0.0      0.0      0.0 payment
		      295.0s       39            1.0            2.2      7.3      7.3      7.3      7.3 stockLevel
		      296.0s       58            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		      296.0s       58            0.0           20.7      0.0      0.0      0.0      0.0 newOrder
		      296.0s       58            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		      296.0s       58            0.0           21.3      0.0      0.0      0.0      0.0 payment
		      296.0s       58            0.0            2.2      0.0      0.0      0.0      0.0 stockLevel:
		  - context canceled

	cluster.go:2368,cdc.go:224,cdc.go:574,test_runner.go:753: error with attached stack trace:
		    main.(*monitor).WaitE
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2356
		    main.(*monitor).Wait
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2364
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:224
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:753
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor failure:
		  - error with attached stack trace:
		    main.(*monitor).wait.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2412
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor task failed:
		  - dial tcp 35.223.231.252:26257
		  - connect
		  - connection refused

	cluster.go:1410,context.go:135,cluster.go:1399,test_runner.go:807: dead node detection: /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod monitor teamcity-1811809-1584430043-24-n4cpu16 --oneshot --ignore-empty-nodes: exit status 1 4: skipped
		3: 4708
		1: dead
		2: 5137
		Error:  1: dead

More

Artifacts: /cdc/sink-chaos/rangefeed=true

See this test on roachdash
powered by pkg/cmd/internal/issues

@cockroach-teamcity
Copy link
Member Author

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@b5f030223fbcf22e806c48a3c46e74a73a54f50f:

		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		     1005.0s        0            1.0            2.1     37.7     37.7     37.7     37.7 delivery
		     1005.0s        0           23.0           21.0     23.1     27.3     27.3     27.3 newOrder
		     1005.0s        0            2.0            2.1      4.5      5.2      5.2      5.2 orderStatus
		     1005.0s        0           17.0           21.2     13.1     17.8     18.9     18.9 payment
		     1005.0s        0            4.0            2.1     10.5     13.1     13.1     13.1 stockLevel
		     1006.0s        0            1.0            2.1     35.7     35.7     35.7     35.7 delivery
		     1006.0s        0           17.0           21.0     22.0     26.2     27.3     27.3 newOrder
		     1006.0s        0            3.0            2.1      4.7      6.0      6.0      6.0 orderStatus
		     1006.0s        0           19.0           21.2     12.1     14.7     14.7     14.7 payment
		     1006.0s        0            5.0            2.1     10.5     13.6     13.6     13.6 stockLevel
		     1007.0s       19            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		     1007.0s       19            5.0           21.0   9663.7  10737.4  10737.4  10737.4 newOrder
		     1007.0s       19            2.0            2.1      4.5      5.0      5.0      5.0 orderStatus
		     1007.0s       19            4.0           21.2     12.6     14.7     14.7     14.7 payment
		     1007.0s       19            1.0            2.1     13.1     13.1     13.1     13.1 stockLevel
		     1008.0s       36            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		     1008.0s       36            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		     1008.0s       36            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		     1008.0s       36            0.0           21.2      0.0      0.0      0.0      0.0 payment
		     1008.0s       36            2.0            2.1      9.4     10.5     10.5     10.5 stockLevel
		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		     1009.0s       51            1.0            2.1     39.8     39.8     39.8     39.8 delivery
		     1009.0s       51            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		     1009.0s       51            1.0            2.1      5.2      5.2      5.2      5.2 orderStatus
		     1009.0s       51            0.0           21.1      0.0      0.0      0.0      0.0 payment
		     1009.0s       51            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel:
		  - context canceled

	cluster.go:2368,cdc.go:224,cdc.go:574,test_runner.go:753: error with attached stack trace:
		    main.(*monitor).WaitE
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2356
		    main.(*monitor).Wait
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2364
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:224
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:753
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor failure:
		  - unexpected node event: 2: dead

	cluster.go:1410,context.go:135,cluster.go:1399,test_runner.go:825: dead node detection: /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod monitor teamcity-1814553-1584515366-23-n4cpu16 --oneshot --ignore-empty-nodes: exit status 1 4: skipped
		2: dead
		3: 4600
		1: 4598
		Error:  2: dead

More

Artifacts: /cdc/sink-chaos/rangefeed=true

See this test on roachdash
powered by pkg/cmd/internal/issues

@cockroach-teamcity
Copy link
Member Author

(roachtest).cdc/sink-chaos/rangefeed=true failed on master@d571624ae4f833c49c717728a74cc7be78a791f0:

		     1132.0s        0            2.0            2.1      5.2      6.6      6.6      6.6 orderStatus
		     1132.0s        0           21.0           21.1     12.1     16.3     17.8     17.8 payment
		     1132.0s        0            1.0            2.1     12.6     12.6     12.6     12.6 stockLevel
		    _elapsed___errors__ops/sec(inst)___ops/sec(cum)__p50(ms)__p95(ms)__p99(ms)_pMax(ms)
		     1133.0s       21            1.0            2.1     30.4     30.4     30.4     30.4 delivery
		     1133.0s       21            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		     1133.0s       21            0.0            2.1      0.0      0.0      0.0      0.0 orderStatus
		     1133.0s       21            0.0           21.1      0.0      0.0      0.0      0.0 payment
		     1133.0s       21            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel
		     1134.0s       37            1.0            2.1     35.7     35.7     35.7     35.7 delivery
		     1134.0s       37            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		     1134.0s       37            1.0            2.1      4.5      4.5      4.5      4.5 orderStatus
		     1134.0s       37            0.0           21.1      0.0      0.0      0.0      0.0 payment
		     1134.0s       37            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel
		     1135.0s       56            0.0            2.1      0.0      0.0      0.0      0.0 delivery
		     1135.0s       56            0.0           20.9      0.0      0.0      0.0      0.0 newOrder
		     1135.0s       56            1.0            2.1      3.7      3.7      3.7      3.7 orderStatus
		     1135.0s       56            0.0           21.0      0.0      0.0      0.0      0.0 payment
		     1135.0s       56            0.0            2.1      0.0      0.0      0.0      0.0 stockLevel:
		  - context canceled

	cluster.go:2368,cdc.go:224,cdc.go:574,test_runner.go:753: error with attached stack trace:
		    main.(*monitor).WaitE
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2356
		    main.(*monitor).Wait
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2364
		    main.cdcBasicTest
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:224
		    main.registerCDC.func4
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cdc.go:574
		    main.(*testRunner).runTest.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/test_runner.go:753
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor failure:
		  - error with attached stack trace:
		    main.(*monitor).wait.func2
		    	/home/agent/work/.go/src/github.com/cockroachdb/cockroach/pkg/cmd/roachtest/cluster.go:2412
		    runtime.goexit
		    	/usr/local/go/src/runtime/asm_amd64.s:1357
		  - monitor task failed:
		  - dial tcp 34.71.177.95:26257
		  - connect
		  - connection refused

	cluster.go:1410,context.go:135,cluster.go:1399,test_runner.go:825: dead node detection: /home/agent/work/.go/src/github.com/cockroachdb/cockroach/bin/roachprod monitor teamcity-1816964-1584603715-26-n4cpu16 --oneshot --ignore-empty-nodes: exit status 1 4: skipped
		1: dead
		2: 4339
		3: 4159
		Error:  1: dead

More

Artifacts: /cdc/sink-chaos/rangefeed=true

See this test on roachdash
powered by pkg/cmd/internal/issues

@jordanlewis
Copy link
Member

F200319 08:53:20.044100 38842 kv/kvserver/concurrency/concurrency_manager.go:288  [n1,s1,r75/1:/Table/5{7-8}] caller violated contract
goroutine 38842 [running]:
github.com/cockroachdb/cockroach/pkg/util/log.getStacks(0x74b4401, 0xed6052a00, 0x0, 0x774589)
	/go/src/github.com/cockroachdb/cockroach/pkg/util/log/get_stacks.go:25 +0xb8
github.com/cockroachdb/cockroach/pkg/util/log.(*loggerT).outputLogEntry(0x74b10c0, 0xc000000004, 0x6a163cb, 0x2e, 0x120, 0xc00bcf83c0, 0x34)
	/go/src/github.com/cockroachdb/cockroach/pkg/util/log/clog.go:212 +0xa0c
github.com/cockroachdb/cockroach/pkg/util/log.addStructured(0x4b86060, 0xc00d211170, 0x4, 0x2, 0x0, 0x0, 0xc008e9fb98, 0x1, 0x1)
	/go/src/github.com/cockroachdb/cockroach/pkg/util/log/structured.go:66 +0x2c9
github.com/cockroachdb/cockroach/pkg/util/log.logDepth(0x4b86060, 0xc00d211170, 0x1, 0x4, 0x0, 0x0, 0xc008e9fb98, 0x1, 0x1)
	/go/src/github.com/cockroachdb/cockroach/pkg/util/log/log.go:44 +0x8c
github.com/cockroachdb/cockroach/pkg/util/log.Fatal(...)
	/go/src/github.com/cockroachdb/cockroach/pkg/util/log/log.go:164
github.com/cockroachdb/cockroach/pkg/kv/kvserver/concurrency.(*managerImpl).OnLockAcquired(0xc00486fd80, 0x4b86060, 0xc00d211170, 0xc00b897ad0)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvserver/concurrency/concurrency_manager.go:288 +0x116
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleReadOnlyLocalEvalResult(0xc003671100, 0x4b86060, 0xc00d211170, 0xc00dffc100, 0x0, 0x0, 0x0, 0x0, 0xc00b897ad0, 0x1, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvserver/replica_read.go:146 +0xa9
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).executeReadOnlyBatch(0xc003671100, 0x4b86060, 0xc00d211170, 0xc00dffc100, 0xc00bc4a070, 0xc00c01b140, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvserver/replica_read.go:85 +0x4dc
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).executeBatchWithConcurrencyRetries(0xc003671100, 0x4b86060, 0xc00d211170, 0xc00dffc100, 0x447c9c8, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvserver/replica_send.go:215 +0x315
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).sendWithRangeID(0xc003671100, 0x4b86060, 0xc00d211140, 0x4b, 0xc00dffc100, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvserver/replica_send.go:98 +0x2f3
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).Send(0xc003671100, 0x4b86060, 0xc00d211140, 0x15fda89491098f0c, 0x3, 0x100000001, 0x1, 0x0, 0x4b, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvserver/replica_send.go:36 +0x91
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).Send(0xc000ebc700, 0x4b86060, 0xc00d2110e0, 0x15fda89491098f0c, 0x3, 0x100000001, 0x1, 0x0, 0x4b, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvserver/store_send.go:204 +0x6c0
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Stores).Send(0xc0008e2be0, 0x4b86060, 0xc00d2110e0, 0x0, 0x0, 0x100000001, 0x1, 0x0, 0x4b, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvserver/stores.go:188 +0xed
github.com/cockroachdb/cockroach/pkg/server.(*Node).batchInternal.func1(0x4b86060, 0xc00d2110e0, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/server/node.go:925 +0x201
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunTaskWithErr(0xc00068a000, 0x4b86060, 0xc00d2110e0, 0x42e413f, 0x10, 0xc008ea12c8, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:302 +0x140
github.com/cockroachdb/cockroach/pkg/server.(*Node).batchInternal(0xc0005eaf00, 0x4b86060, 0xc00d2110e0, 0xc00dffc080, 0xc00d2110e0, 0x80, 0x80)
	/go/src/github.com/cockroachdb/cockroach/pkg/server/node.go:913 +0x194
github.com/cockroachdb/cockroach/pkg/server.(*Node).Batch(0xc0005eaf00, 0x4b86060, 0xc00d2110b0, 0xc00dffc080, 0xc00d211050, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/server/node.go:951 +0x9f
github.com/cockroachdb/cockroach/pkg/rpc.internalClientAdapter.Batch(...)
	/go/src/github.com/cockroachdb/cockroach/pkg/rpc/context.go:537
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*grpcTransport).sendBatch(0xc00b06cf40, 0x4b86060, 0xc00d2110b0, 0x1, 0x4b437a0, 0xc0002cb880, 0x0, 0x0, 0x100000001, 0x1, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/transport.go:199 +0x127
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*grpcTransport).SendNext(0xc00b06cf40, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x100000001, 0x1, 0x0, 0x4b, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/transport.go:168 +0x160
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*DistSender).sendToReplicas(0xc0008ecc60, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x4b, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/dist_sender.go:1630 +0x2a2
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*DistSender).sendRPC(0xc0008ecc60, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x4b, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/dist_sender.go:460 +0x1a9
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*DistSender).sendSingleRange(0xc0008ecc60, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/dist_sender.go:542 +0x2ee
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*DistSender).sendPartialBatch(0xc0008ecc60, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/dist_sender.go:1401 +0x3cf
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*DistSender).divideAndSendBatchToRanges(0xc0008ecc60, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/dist_sender.go:1086 +0x1580
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*DistSender).Send(0xc0008ecc60, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/dist_sender.go:737 +0x785
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*txnLockGatekeeper).SendLocked(0xc00688b190, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_lock_gatekeeper.go:86 +0x10b
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*txnMetricRecorder).SendLocked(0xc00688b158, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_interceptor_metric_recorder.go:46 +0x8d
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*txnCommitter).SendLocked(0xc00688b128, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_interceptor_committer.go:126 +0x6f1
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*txnSpanRefresher).sendLockedWithRefreshAttempts(0xc00688b0b8, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_interceptor_span_refresher.go:225 +0x9b
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*txnSpanRefresher).SendLocked(0xc00688b0b8, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_interceptor_span_refresher.go:183 +0x180
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*txnPipeliner).SendLocked(0xc00688b008, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_interceptor_pipeliner.go:224 +0x159
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*txnSeqNumAllocator).SendLocked(0xc00688afe8, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_interceptor_seq_num_allocator.go:105 +0x20c
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*txnHeartbeater).SendLocked(0xc00688af48, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_interceptor_heartbeater.go:172 +0x1a9
github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord.(*TxnCoordSender).Send(0xc00688ad80, 0x4b86060, 0xc00d211050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/kvclient/kvcoord/txn_coord_sender.go:504 +0x406
github.com/cockroachdb/cockroach/pkg/kv.(*DB).sendUsingSender(0xc0006d4380, 0x4b85fa0, 0xc00b06cdc0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/db.go:739 +0x122
github.com/cockroachdb/cockroach/pkg/kv.(*Txn).Send(0xc005300e10, 0x4b85fa0, 0xc00b06cdc0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/kv/txn.go:894 +0x11e
github.com/cockroachdb/cockroach/pkg/sql/row.makeKVBatchFetcher.func1(0x4b85fa0, 0xc00b06cdc0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/row/kv_batch_fetcher.go:184 +0x76
github.com/cockroachdb/cockroach/pkg/sql/row.(*txnKVFetcher).fetch(0xc007373e10, 0x4b85fa0, 0xc00b06cdc0, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/row/kv_batch_fetcher.go:315 +0x6b1
github.com/cockroachdb/cockroach/pkg/sql/row.(*txnKVFetcher).nextBatch(0xc007373e10, 0x4b85fa0, 0xc00b06cdc0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/row/kv_batch_fetcher.go:399 +0x1af
github.com/cockroachdb/cockroach/pkg/sql/row.(*KVFetcher).NextKV(0xc00dffc000, 0x4b85fa0, 0xc00b06cdc0, 0xc008ea4688, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/row/kv_fetcher.go:87 +0x114
github.com/cockroachdb/cockroach/pkg/sql/row.(*Fetcher).NextKey(0xc008556360, 0x4b85fa0, 0xc00b06cdc0, 0x0, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/row/fetcher.go:598 +0x5e
github.com/cockroachdb/cockroach/pkg/sql/row.(*Fetcher).StartScanFrom(0xc008556360, 0x4b85fa0, 0xc00b06cdc0, 0x4b3ee20, 0xc007373e10, 0x0, 0x100000004)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/row/fetcher.go:588 +0x97
github.com/cockroachdb/cockroach/pkg/sql/row.(*Fetcher).StartScan(0xc008556360, 0x4b85fa0, 0xc00b06cdc0, 0xc005300e10, 0xc005c4f500, 0x1, 0xf, 0xc008ea4900, 0x0, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/row/fetcher.go:484 +0x1da
github.com/cockroachdb/cockroach/pkg/sql/rowexec.(*tableReader).Start(0xc00d637100, 0x4b85fa0, 0xc00b06cdc0, 0x1, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/rowexec/tablereader.go:163 +0x23c
github.com/cockroachdb/cockroach/pkg/sql.(*rowSourceToPlanNode).startExec(0xc010b0e280, 0x4b85fa0, 0xc00b06cdc0, 0xc010b0e000, 0xc0041e23b8, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/row_source_to_plan_node.go:68 +0x47
github.com/cockroachdb/cockroach/pkg/sql.startExec.func2(0x42fccc8, 0x17, 0x4b885e0, 0xc010b0e280, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/plan.go:394 +0xb9
github.com/cockroachdb/cockroach/pkg/sql.(*planVisitor).visitInternal.func1(0xc008ea5c70, 0x42fccc8, 0x17, 0x4b885e0, 0xc010b0e280)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/walk.go:143 +0x5d
github.com/cockroachdb/cockroach/pkg/sql.(*planVisitor).visitInternal(0xc008ea5c70, 0x4b885e0, 0xc010b0e280, 0x42fccc8, 0x17)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/walk.go:720 +0x2a6
github.com/cockroachdb/cockroach/pkg/sql.(*planVisitor).visit(0xc008ea5c70, 0x4b885e0, 0xc010b0e280, 0x6, 0x4b88b60)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/walk.go:110 +0x8d
github.com/cockroachdb/cockroach/pkg/sql.(*planVisitor).visitInternal(0xc008ea5c70, 0x4b88b60, 0xc00a62d700, 0x42c830a, 0x6)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/walk.go:580 +0x4af9
github.com/cockroachdb/cockroach/pkg/sql.(*planVisitor).visitConcrete(0xc008ea5c70, 0x4b88b60, 0xc00a62d700)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/walk.go:123 +0x81
github.com/cockroachdb/cockroach/pkg/sql.(*planVisitor).visitInternal(0xc008ea5c70, 0x4b887a0, 0xc00d210bd0, 0x42c0a76, 0x3)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/walk.go:605 +0x1adf
github.com/cockroachdb/cockroach/pkg/sql.(*planVisitor).visit(0xc008ea5c70, 0x4b887a0, 0xc00d210bd0, 0x75049d8, 0x75049d8)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/walk.go:110 +0x8d
github.com/cockroachdb/cockroach/pkg/sql.walkPlan(...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/walk.go:74
github.com/cockroachdb/cockroach/pkg/sql.startExec(0x4b85fa0, 0xc00b06cdc0, 0xc010b0e000, 0xc0041e23b8, 0x4b887a0, 0xc00d210bd0, 0x0, 0x4)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/plan.go:397 +0x1bb
github.com/cockroachdb/cockroach/pkg/sql.(*planNodeToRowSource).Start(0xc00498d400, 0x4b85fa0, 0xc00b06cdc0, 0x7507360, 0x3c87520)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/plan_node_to_row_source.go:117 +0xd6
github.com/cockroachdb/cockroach/pkg/sql/execinfra.(*ProcessorBase).Run(0xc00498d400, 0x4b85fa0, 0xc00b06cdc0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/execinfra/processorsbase.go:748 +0x52
github.com/cockroachdb/cockroach/pkg/sql/flowinfra.(*FlowBase).Run(0xc008556120, 0x4b85fa0, 0xc00b06cdc0, 0x447d4e0, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/flowinfra/flow.go:370 +0x247
github.com/cockroachdb/cockroach/pkg/sql.(*DistSQLPlanner).Run(0xc0003f0f00, 0xc00c01ae40, 0xc005300e10, 0xc008ea6458, 0xc010010b00, 0xc0041e2498, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/distsql_running.go:378 +0x426
github.com/cockroachdb/cockroach/pkg/sql.(*DistSQLPlanner).PlanAndRun(0xc0003f0f00, 0x4b86060, 0xc00d210720, 0xc0041e2498, 0xc00c01ae40, 0xc005300e10, 0x4b88560, 0xc00c6945a0, 0xc010010b00, 0xc0041e21b0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/distsql_running.go:988 +0x225
github.com/cockroachdb/cockroach/pkg/sql.(*connExecutor).execWithDistSQLEngine(0xc0041e2000, 0x4b86060, 0xc00d210720, 0xc0041e23b8, 0x3, 0x7f43d9576d28, 0xc006576680, 0xc008ea6700, 0xc00fae4c28, 0x0, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/conn_executor_exec.go:868 +0x3e3
github.com/cockroachdb/cockroach/pkg/sql.(*connExecutor).dispatchToExecutionEngine(0xc0041e2000, 0x4b86060, 0xc00d210720, 0xc0041e23b8, 0x7f43d9576d28, 0xc006576680, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/conn_executor_exec.go:766 +0x6cd
github.com/cockroachdb/cockroach/pkg/sql.(*connExecutor).execStmtInOpenState(0xc0041e2000, 0x4b86060, 0xc00d210720, 0x4ba04a0, 0xc006d1c300, 0xc00432c26d, 0x7e, 0x2, 0x1, 0xc0068b4e40, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/conn_executor_exec.go:471 +0xaf0
github.com/cockroachdb/cockroach/pkg/sql.(*connExecutor).execStmt(0xc0041e2000, 0x4b86060, 0xc00d210720, 0x4ba04a0, 0xc006d1c300, 0xc00432c26d, 0x7e, 0x2, 0x1, 0xc0068b4e40, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/conn_executor_exec.go:94 +0x4f6
github.com/cockroachdb/cockroach/pkg/sql.(*connExecutor).execCmd(0xc0041e2000, 0x4b85fa0, 0xc00d1ecf40, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/conn_executor.go:1456 +0xed8
github.com/cockroachdb/cockroach/pkg/sql.(*connExecutor).run(0xc0041e2000, 0x4b85fa0, 0xc006bb42c0, 0xc000221780, 0x5400, 0x15000, 0xc000221818, 0xc0052d4320, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/conn_executor.go:1323 +0x1f2
github.com/cockroachdb/cockroach/pkg/sql.(*Server).ServeConn(0xc000d91180, 0x4b85fa0, 0xc006bb42c0, 0xc0041e2000, 0x5400, 0x15000, 0xc000221818, 0xc0052d4320, 0x0, 0x0)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/conn_executor.go:478 +0x104
github.com/cockroachdb/cockroach/pkg/sql/pgwire.(*conn).processCommandsAsync.func1(0xc00a1c804e, 0xc005bd03c0, 0x4b85fa0, 0xc006bb42c0, 0xc0052d4320, 0xc000d91180, 0xc006576000, 0x4ba9aa0, 0xc009bf91d0, 0xc006c24300, ...)
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/pgwire/conn.go:594 +0x2e8
created by github.com/cockroachdb/cockroach/pkg/sql/pgwire.(*conn).processCommandsAsync
	/go/src/github.com/cockroachdb/cockroach/pkg/sql/pgwire/conn.go:526 +0x17e

@nvanbenschoten

@nvanbenschoten
Copy link
Member

nvanbenschoten commented Mar 19, 2020

Same issue as #46290. Tracking over there.

nvanbenschoten added a commit to nvanbenschoten/cockroach that referenced this issue Mar 21, 2020
Fixes cockroachdb#46290.
Fixes cockroachdb#43735.

This change adjusts the lockTable to be more lenient to the timestamp
of new lock acquisitions. Specifically, it lifts the restriction that
all calls to AcquireLock use monotonically increasing timestamps.
Instead, it properly handles apparent timestamp regressions by
ratcheting lock timestamps instead of replacing them directly.

This matches the corresponding behavior in MVCC:
  https://github.com/cockroachdb/cockroach/blob/92107b551bbafe54fddb496442c590cb6feb5d65/pkg/storage/mvcc.go#L1631

This leniency is needed for sequences of events like the following:
- txn A acquires lock at epoch 1, ts 10
- txn B pushes txn A to ts 20
- txn B updates lock to ts 20
- txn A restarts at ts 15 without noticing that it has been pushes
- txn A re-acquires lock at epoch 2, ts 15
- we hit the lock timestamp regression assertion

We see this frequently in CDC roachtests because the rangefeed
processor performs high-priority timestamp pushes on long-running
transactions. Outside of CDC, this is rare in our system.

Release note (bug fix): CDC no longer combines with long
running transactions to trigger an assertion.

Release justification: fixes a high-priority bug in existing
functionality. The bug could crash a server if the right sequence
of events occurred. This was typically rare, but was much more
common when CDC was in use.
craig bot pushed a commit that referenced this issue Mar 23, 2020
46391: kv/concurrency: never regress timestamp in lockTable on lock acquisition r=sumeerbhola a=nvanbenschoten

Fixes #46290.
Fixes #43735.
Fixes #41425.
Fixes #43809.

This change adjusts the lockTable to be more lenient to the timestamp of new lock acquisitions. Specifically, it lifts the restriction that all calls to AcquireLock use monotonically increasing timestamps. Instead, it properly handles apparent timestamp regressions by ratcheting lock timestamps instead of replacing them directly.

This matches the corresponding behavior in MVCC: https://github.com/cockroachdb/cockroach/blob/92107b551bbafe54fddb496442c590cb6feb5d65/pkg/storage/mvcc.go#L1631

This leniency is needed for sequences of events like the following:
- txn A acquires lock at epoch 1, ts 10
- txn B pushes txn A to ts 20
- txn B updates lock to ts 20
- txn A restarts at ts 15 without noticing that it has been pushes
- txn A re-acquires lock at epoch 2, ts 15
- we hit the lock timestamp regression assertion

We see this frequently in CDC roachtests because the rangefeed processor performs high-priority timestamp pushes on long-running transactions. Outside of CDC, this is rare in our system.

Release note (bug fix): CDC no longer combines with long running transactions to trigger an assertion.

Release justification: fixes a high-priority bug in existing functionality. The bug could crash a server if the right sequence of events occurred. This was typically rare, but was much more common when CDC was in use. This is a fairly large PR, but there's only a single line of non-testing code touched.

46415: sql: misc savepoint fixes r=andreimatei a=knz

Fixes #46372 

Release justification: bug fixes and low-risk updates to new functionality

These commits correct a few gaps in the recent implementation.

Co-authored-by: Nathan VanBenschoten <[email protected]>
Co-authored-by: Raphael 'kena' Poss <[email protected]>
@craig craig bot closed this as completed in 3069773 Mar 23, 2020
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
branch-master Failures and bugs on the master branch. C-test-failure Broken test (automatically or manually discovered). O-roachtest O-robot Originated from a bot.
Projects
None yet
Development

Successfully merging a pull request may close this issue.

5 participants