[2024/05/19 15:06:31.097 +08:00] [INFO] [meminfo.go:196] ["use cgroup memory hook because pd is in the container"] [2024/05/19 15:06:31.097 +08:00] [INFO] [versioninfo.go:98] ["Welcome to Placement Driver (PD)"] [2024/05/19 15:06:31.097 +08:00] [INFO] [versioninfo.go:99] [PD] [release-version=v8.2.0-alpha-37-g6ded6f8db] [2024/05/19 15:06:31.097 +08:00] [INFO] [versioninfo.go:100] [PD] [edition=Community] [2024/05/19 15:06:31.097 +08:00] [INFO] [versioninfo.go:101] [PD] [git-hash=6ded6f8db51bae0af5c948a532545792f7543959] [2024/05/19 15:06:31.097 +08:00] [INFO] [versioninfo.go:102] [PD] [git-branch=master] [2024/05/19 15:06:31.097 +08:00] [INFO] [versioninfo.go:103] [PD] [utc-build-time="2024-05-18 05:54:02"] [2024/05/19 15:06:31.097 +08:00] [INFO] [metricutil.go:86] ["disable Prometheus push client"] [2024/05/19 15:06:31.097 +08:00] [INFO] [server.go:254] ["PD config"] [config="{\"client-urls\":\"http://127.0.0.1:2399\",\"peer-urls\":\"http://127.0.0.1:2398\",\"advertise-client-urls\":\"http://127.0.0.1:2399\",\"advertise-peer-urls\":\"http://127.0.0.1:2398\",\"name\":\"pd3\",\"data-dir\":\"/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pd3\",\"force-new-cluster\":true,\"enable-grpc-gateway\":true,\"initial-cluster\":\"pd3=http://127.0.0.1:2398\",\"initial-cluster-state\":\"new\",\"initial-cluster-token\":\"pd-cluster\",\"join\":\"\",\"lease\":3,\"log\":{\"level\":\"info\",\"format\":\"text\",\"disable-timestamp\":false,\"file\":{\"filename\":\"\",\"max-size\":0,\"max-days\":0,\"max-backups\":0},\"development\":false,\"disable-caller\":false,\"disable-stacktrace\":false,\"disable-error-verbose\":true,\"sampling\":null,\"error-output-path\":\"\"},\"max-concurrent-tso-proxy-streamings\":5000,\"tso-proxy-recv-from-client-timeout\":\"1h0m0s\",\"tso-save-interval\":\"3s\",\"tso-update-physical-interval\":\"50ms\",\"enable-local-tso\":false,\"metric\":{\"job\":\"pd3\",\"address\":\"\",\"interval\":\"15s\"},\"schedule\":{\"max-snapshot-count\":64,\"max-pending-peer-count\":64,\"max-merge-region-size\":20,\"max-merge-region-keys\":0,\"split-merge-interval\":\"1h0m0s\",\"switch-witness-interval\":\"1h0m0s\",\"enable-one-way-merge\":\"false\",\"enable-cross-table-merge\":\"true\",\"patrol-region-interval\":\"10ms\",\"max-store-down-time\":\"30m0s\",\"max-store-preparing-time\":\"48h0m0s\",\"leader-schedule-limit\":4,\"leader-schedule-policy\":\"count\",\"region-schedule-limit\":2048,\"witness-schedule-limit\":4,\"replica-schedule-limit\":64,\"merge-schedule-limit\":8,\"hot-region-schedule-limit\":4,\"hot-region-cache-hits-threshold\":3,\"store-limit\":{},\"tolerant-size-ratio\":0,\"low-space-ratio\":0.8,\"high-space-ratio\":0.7,\"region-score-formula-version\":\"v2\",\"scheduler-max-waiting-operator\":5,\"enable-remove-down-replica\":\"true\",\"enable-replace-offline-replica\":\"true\",\"enable-make-up-replica\":\"true\",\"enable-remove-extra-replica\":\"true\",\"enable-location-replacement\":\"true\",\"enable-debug-metrics\":\"false\",\"enable-joint-consensus\":\"true\",\"enable-tikv-split-region\":\"true\",\"enable-heartbeat-breakdown-metrics\":\"true\",\"enable-heartbeat-concurrent-runner\":\"true\",\"schedulers-v2\":[{\"type\":\"balance-region\",\"args\":null,\"disable\":false,\"args-payload\":\"\"},{\"type\":\"balance-leader\",\"args\":null,\"disable\":false,\"args-payload\":\"\"},{\"type\":\"hot-region\",\"args\":null,\"disable\":false,\"args-payload\":\"\"},{\"type\":\"evict-slow-store\",\"args\":null,\"disable\":false,\"args-payload\":\"\"}],\"schedulers-payload\":null,\"hot-regions-write-interval\":\"10m0s\",\"hot-regions-reserved-days\":7,\"max-movable-hot-peer-size\":512,\"enable-diagnostic\":\"true\",\"enable-witness\":\"false\",\"slow-store-evicting-affected-store-ratio-threshold\":0.3,\"store-limit-version\":\"v1\"},\"replication\":{\"max-replicas\":3,\"location-labels\":\"\",\"strictly-match-label\":\"false\",\"enable-placement-rules\":\"true\",\"enable-placement-rules-cache\":\"false\",\"isolation-level\":\"\"},\"pd-server\":{\"use-region-storage\":\"true\",\"max-gap-reset-ts\":\"24h0m0s\",\"key-type\":\"table\",\"runtime-services\":\"\",\"metric-storage\":\"\",\"dashboard-address\":\"auto\",\"flow-round-by-digit\":3,\"min-resolved-ts-persistence-interval\":\"1s\",\"server-memory-limit\":0,\"server-memory-limit-gc-trigger\":0.7,\"enable-gogc-tuner\":\"false\",\"gc-tuner-threshold\":0.6,\"block-safe-point-v1\":\"false\"},\"cluster-version\":\"0.0.0\",\"labels\":{},\"quota-backend-bytes\":\"8GiB\",\"auto-compaction-mode\":\"periodic\",\"auto-compaction-retention-v2\":\"1h\",\"TickInterval\":\"500ms\",\"ElectionInterval\":\"3s\",\"PreVote\":true,\"max-request-bytes\":157286400,\"security\":{\"cacert-path\":\"\",\"cert-path\":\"\",\"key-path\":\"\",\"cert-allowed-cn\":null,\"SSLCABytes\":null,\"SSLCertBytes\":null,\"SSLKEYBytes\":null,\"redact-info-log\":false,\"encryption\":{\"data-encryption-method\":\"plaintext\",\"data-key-rotation-period\":\"168h0m0s\",\"master-key\":{\"type\":\"plaintext\",\"key-id\":\"\",\"region\":\"\",\"endpoint\":\"\",\"path\":\"\"}}},\"label-property\":null,\"WarningMsgs\":null,\"DisableStrictReconfigCheck\":false,\"HeartbeatStreamBindInterval\":\"1m0s\",\"LeaderPriorityCheckInterval\":\"1m0s\",\"dashboard\":{\"tidb-cacert-path\":\"\",\"tidb-cert-path\":\"\",\"tidb-key-path\":\"\",\"public-path-prefix\":\"\",\"internal-proxy\":false,\"enable-telemetry\":false,\"enable-experimental\":false},\"replication-mode\":{\"replication-mode\":\"majority\",\"dr-auto-sync\":{\"label-key\":\"\",\"primary\":\"\",\"dr\":\"\",\"primary-replicas\":0,\"dr-replicas\":0,\"wait-store-timeout\":\"1m0s\",\"wait-recover-timeout\":\"0s\",\"pause-region-split\":\"false\"}},\"keyspace\":{\"pre-alloc\":null,\"wait-region-split\":true,\"wait-region-split-timeout\":\"30s\",\"check-region-split-interval\":\"50ms\"},\"micro-service\":{\"enable-scheduling-fallback\":\"true\"},\"controller\":{\"degraded-mode-wait-duration\":\"0s\",\"ltb-max-wait-duration\":\"30s\",\"request-unit\":{\"read-base-cost\":0.125,\"read-per-batch-base-cost\":0.5,\"read-cost-per-byte\":0.0000152587890625,\"write-base-cost\":1,\"write-per-batch-base-cost\":1,\"write-cost-per-byte\":0.0009765625,\"read-cpu-ms-cost\":0.3333333333333333},\"enable-controller-trace-log\":\"false\"}}"] [2024/05/19 15:06:31.101 +08:00] [INFO] [apiutil.go:413] ["register REST path"] [path=/pd/api/v1] [2024/05/19 15:06:31.102 +08:00] [INFO] [apiutil.go:413] ["register REST path"] [path=/pd/api/v2/] [2024/05/19 15:06:31.102 +08:00] [INFO] [apiutil.go:413] ["register REST path"] [path=/autoscaling] [2024/05/19 15:06:31.102 +08:00] [INFO] [distro.go:51] ["using distribution strings"] [strings={}] [2024/05/19 15:06:31.103 +08:00] [INFO] [apiutil.go:413] ["register REST path"] [path=/dashboard/api/] [2024/05/19 15:06:31.103 +08:00] [INFO] [apiutil.go:413] ["register REST path"] [path=/dashboard/] [2024/05/19 15:06:31.103 +08:00] [INFO] [registry.go:92] ["restful API service registered successfully"] [prefix=pd3] [service-name=MetaStorage] [2024/05/19 15:06:31.103 +08:00] [INFO] [apiutil.go:413] ["register REST path"] [path=/resource-manager/api/v1/] [2024/05/19 15:06:31.103 +08:00] [INFO] [registry.go:92] ["restful API service registered successfully"] [prefix=pd3] [service-name=ResourceManager] [2024/05/19 15:06:31.103 +08:00] [WARN] [config.go:622] ["Running http and grpc server on single port. This is not recommended for production."] [2024/05/19 15:06:31.103 +08:00] [INFO] [etcd.go:120] ["configuring peer listeners"] [listen-peer-urls="[http://127.0.0.1:2398]"] [2024/05/19 15:06:31.103 +08:00] [INFO] [systimemon.go:30] ["start system time monitor"] [2024/05/19 15:06:31.103 +08:00] [INFO] [etcd.go:130] ["configuring client listeners"] [listen-client-urls="[http://127.0.0.1:2399]"] [2024/05/19 15:06:31.103 +08:00] [INFO] [etcd.go:634] ["pprof is enabled"] [path=/debug/pprof] [2024/05/19 15:06:31.103 +08:00] [INFO] [etcd.go:309] ["starting an etcd server"] [etcd-version=3.4.31] [git-sha="Not provided (use ./build instead of go build)"] [go-version=go1.21.6] [go-os=linux] [go-arch=amd64] [max-cpu-set=62] [max-cpu-available=62] [member-initialized=false] [name=pd3] [data-dir=/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pd3] [wal-dir=] [wal-dir-dedicated=] [member-dir=/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pd3/member] [force-new-cluster=true] [heartbeat-interval=500ms] [election-timeout=3s] [initial-election-tick-advance=true] [snapshot-count=100000] [max-wals=5] [max-snapshots=5] [snapshot-catchup-entries=5000] [initial-advertise-peer-urls="[http://127.0.0.1:2398]"] [listen-peer-urls="[http://127.0.0.1:2398]"] [advertise-client-urls="[http://127.0.0.1:2399]"] [listen-client-urls="[http://127.0.0.1:2399]"] [listen-metrics-urls="[]"] [cors="[*]"] [host-whitelist="[*]"] [initial-cluster="pd3=http://127.0.0.1:2398"] [initial-cluster-state=new] [initial-cluster-token=pd-cluster] [quota-backend-bytes=8589934592] [max-request-bytes=157286400] [max-concurrent-streams=4294967295] [pre-vote=true] [initial-corrupt-check=false] [corrupt-check-time-interval=0s] [auto-compaction-mode=periodic] [auto-compaction-retention=1h0m0s] [auto-compaction-interval=1h0m0s] [discovery-url=] [discovery-proxy=] [2024/05/19 15:06:31.103 +08:00] [WARN] [server.go:297] ["exceeded recommended request limit"] [max-request-bytes=157286400] [max-request-size="157 MB"] [recommended-request-bytes=10485760] [recommended-request-size="10 MB"] [2024/05/19 15:06:31.104 +08:00] [INFO] [backend.go:80] ["opened backend db"] [path=/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pd3/member/snap/db] [took=470.142µs] [2024/05/19 15:06:31.105 +08:00] [INFO] [raft.go:537] ["starting local member"] [local-member-id=820b37984af3d5a4] [cluster-id=ead61e552c96788c] [2024/05/19 15:06:31.105 +08:00] [INFO] [raft.go:1523] ["820b37984af3d5a4 switched to configuration voters=()"] [2024/05/19 15:06:31.106 +08:00] [INFO] [raft.go:706] ["820b37984af3d5a4 became follower at term 0"] [2024/05/19 15:06:31.106 +08:00] [INFO] [raft.go:389] ["newRaft 820b37984af3d5a4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"] [2024/05/19 15:06:31.106 +08:00] [INFO] [raft.go:706] ["820b37984af3d5a4 became follower at term 1"] [2024/05/19 15:06:31.106 +08:00] [INFO] [raft.go:1523] ["820b37984af3d5a4 switched to configuration voters=(9370644576906499492)"] [2024/05/19 15:06:31.106 +08:00] [WARN] [store.go:1391] ["simple token is not cryptographically signed"] [2024/05/19 15:06:31.107 +08:00] [INFO] [quota.go:126] ["enabled backend quota"] [quota-name=v3-applier] [quota-size-bytes=8589934592] [quota-size="8.6 GB"] [2024/05/19 15:06:31.107 +08:00] [INFO] [server.go:831] ["starting etcd server"] [local-member-id=820b37984af3d5a4] [local-server-version=3.4.31] [cluster-version=to_be_decided] [2024/05/19 15:06:31.107 +08:00] [INFO] [server.go:697] ["started as single-node; fast-forwarding election ticks"] [local-member-id=820b37984af3d5a4] [forward-ticks=5] [forward-duration=2.5s] [election-ticks=6] [election-timeout=3s] [2024/05/19 15:06:31.107 +08:00] [INFO] [purge.go:48] ["started to purge file"] [dir=/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pd3/member/snap] [suffix=snap.db] [max=5] [interval=30s] [2024/05/19 15:06:31.107 +08:00] [INFO] [purge.go:48] ["started to purge file"] [dir=/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pd3/member/snap] [suffix=snap] [max=5] [interval=30s] [2024/05/19 15:06:31.107 +08:00] [INFO] [purge.go:48] ["started to purge file"] [dir=/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pd3/member/wal] [suffix=wal] [max=5] [interval=30s] [2024/05/19 15:06:31.107 +08:00] [INFO] [raft.go:1523] ["820b37984af3d5a4 switched to configuration voters=(9370644576906499492)"] [2024/05/19 15:06:31.107 +08:00] [INFO] [cluster.go:395] ["added member"] [cluster-id=ead61e552c96788c] [local-member-id=820b37984af3d5a4] [added-peer-id=820b37984af3d5a4] [added-peer-peer-urls="[http://127.0.0.1:2398]"] [2024/05/19 15:06:31.109 +08:00] [INFO] [etcd.go:251] ["now serving peer/client/metrics"] [local-member-id=820b37984af3d5a4] [initial-advertise-peer-urls="[http://127.0.0.1:2398]"] [listen-peer-urls="[http://127.0.0.1:2398]"] [advertise-client-urls="[http://127.0.0.1:2399]"] [listen-client-urls="[http://127.0.0.1:2399]"] [listen-metrics-urls="[]"] [2024/05/19 15:06:31.109 +08:00] [INFO] [etcd.go:605] ["serving peer traffic"] [address=127.0.0.1:2398] [2024/05/19 15:06:32.607 +08:00] [INFO] [raft.go:929] ["820b37984af3d5a4 is starting a new election at term 1"] [2024/05/19 15:06:32.607 +08:00] [INFO] [raft.go:735] ["820b37984af3d5a4 became pre-candidate at term 1"] [2024/05/19 15:06:32.607 +08:00] [INFO] [raft.go:830] ["820b37984af3d5a4 received MsgPreVoteResp from 820b37984af3d5a4 at term 1"] [2024/05/19 15:06:32.607 +08:00] [INFO] [raft.go:719] ["820b37984af3d5a4 became candidate at term 2"] [2024/05/19 15:06:32.607 +08:00] [INFO] [raft.go:830] ["820b37984af3d5a4 received MsgVoteResp from 820b37984af3d5a4 at term 2"] [2024/05/19 15:06:32.607 +08:00] [INFO] [raft.go:771] ["820b37984af3d5a4 became leader at term 2"] [2024/05/19 15:06:32.607 +08:00] [INFO] [node.go:327] ["raft.node: 820b37984af3d5a4 elected leader 820b37984af3d5a4 at term 2"] [2024/05/19 15:06:32.607 +08:00] [INFO] [server.go:2639] ["setting up initial cluster version"] [cluster-version=3.4] [2024/05/19 15:06:32.607 +08:00] [INFO] [cluster.go:561] ["set initial cluster version"] [cluster-id=ead61e552c96788c] [local-member-id=820b37984af3d5a4] [cluster-version=3.4] [2024/05/19 15:06:32.607 +08:00] [INFO] [capability.go:77] ["enabled capabilities for version"] [cluster-version=3.4] [2024/05/19 15:06:32.607 +08:00] [INFO] [serve.go:100] ["ready to serve client requests"] [2024/05/19 15:06:32.607 +08:00] [INFO] [server.go:2671] ["cluster version is updated"] [cluster-version=3.4] [2024/05/19 15:06:32.607 +08:00] [INFO] [server.go:2140] ["published local member to cluster through raft"] [local-member-id=820b37984af3d5a4] [local-member-attributes="{Name:pd3 ClientURLs:[http://127.0.0.1:2399]}"] [request-path=/0/members/820b37984af3d5a4/attributes] [cluster-id=ead61e552c96788c] [publish-timeout=11s] [2024/05/19 15:06:32.608 +08:00] [INFO] [registry.go:69] ["gRPC service already registered"] [prefix=pd3] [service-name=MetaStorage] [2024/05/19 15:06:32.608 +08:00] [INFO] [registry.go:69] ["gRPC service already registered"] [prefix=pd3] [service-name=ResourceManager] [2024/05/19 15:06:32.608 +08:00] [INFO] [serve.go:209] ["serving client traffic insecurely; this is strongly discouraged!"] [traffic=grpc+http] [address=127.0.0.1:2399] [2024/05/19 15:06:32.610 +08:00] [INFO] [server.go:438] ["init cluster id"] [cluster-id=7370603652854261375] [2024/05/19 15:06:32.610 +08:00] [INFO] [member.go:349] ["member joining election"] [member-info="name:\"pd3\" member_id:9370644576906499492 peer_urls:\"http://127.0.0.1:2398\" client_urls:\"http://127.0.0.1:2399\" "] [root-path=/pd/7370603652854261375] [2024/05/19 15:06:32.613 +08:00] [INFO] [allocator_manager.go:741] ["entering into allocator daemon"] [] [2024/05/19 15:06:32.613 +08:00] [INFO] [allocator_manager.go:456] ["delete the dc-location key previously written in etcd"] [] [server-id=9370644576906499492] [2024/05/19 15:06:32.614 +08:00] [INFO] [history_buffer.go:154] ["start from history index"] [start-index=0] [2024/05/19 15:06:32.615 +08:00] [INFO] [server.go:515] ["triggering the start callback functions"] [2024/05/19 15:06:32.615 +08:00] [INFO] [manager.go:44] ["meta storage starts to initialize"] [name=pd3] [2024/05/19 15:06:32.615 +08:00] [INFO] [manager.go:99] ["resource group manager starts to initialize"] [name=pd3] [2024/05/19 15:06:32.616 +08:00] [INFO] [cgmon.go:58] ["cgroup monitor started"] [2024/05/19 15:06:32.616 +08:00] [INFO] [cgmon.go:133] ["set the maxprocs"] [quota=8] [2024/05/19 15:06:32.616 +08:00] [INFO] [server.go:1706] ["start to campaign PD leader"] [campaign-leader-name=pd3] [2024/05/19 15:06:32.617 +08:00] [INFO] [cgmon.go:157] ["set the memory limit"] [mem-limit=34359738368] [2024/05/19 15:06:32.617 +08:00] [INFO] [lease.go:66] ["lease granted"] [lease-id=6171215236639834637] [lease-timeout=3] [purpose="leader election"] [2024/05/19 15:06:32.617 +08:00] [INFO] [leadership.go:181] ["check campaign resp"] [resp="{\"header\":{\"cluster_id\":16921746001060329612,\"member_id\":9370644576906499492,\"revision\":6,\"raft_term\":2},\"succeeded\":true,\"responses\":[{\"Response\":{\"ResponsePut\":{\"header\":{\"revision\":6}}}}]}"] [2024/05/19 15:06:32.617 +08:00] [INFO] [leadership.go:190] ["write leaderData to leaderPath ok"] [leader-key=/pd/7370603652854261375/leader] [purpose="leader election"] [2024/05/19 15:06:32.617 +08:00] [INFO] [server.go:1732] ["campaign PD leader ok"] [campaign-leader-name=pd3] [2024/05/19 15:06:32.617 +08:00] [INFO] [server.go:1740] ["initializing the global TSO allocator"] [2024/05/19 15:06:32.617 +08:00] [INFO] [tso.go:160] ["start to sync timestamp"] [] [2024/05/19 15:06:32.618 +08:00] [INFO] [lease.go:155] ["start lease keep alive worker"] [interval=1s] [purpose="leader election"] [2024/05/19 15:06:32.618 +08:00] [INFO] [tso.go:220] ["sync and save timestamp"] [] [last=0001/01/01 00:00:00.000 +00:00] [last-saved=0001/01/01 00:00:00.000 +00:00] [save=2024/05/19 15:06:35.618 +08:00] [next=2024/05/19 15:06:32.618 +08:00] [2024/05/19 15:06:32.619 +08:00] [INFO] [server.go:1878] ["server enable region storage"] [2024/05/19 15:06:32.619 +08:00] [INFO] [server.go:1772] ["triggering the leader callback functions"] [2024/05/19 15:06:32.619 +08:00] [ERROR] [manager.go:124] ["un-marshall controller config failed, fallback to default"] [error="unexpected end of JSON input"] [v=] [2024/05/19 15:06:32.621 +08:00] [INFO] [manager.go:185] ["resource group manager finishes initialization"] [2024/05/19 15:06:32.624 +08:00] [INFO] [id.go:174] ["idAllocator allocates a new id"] [new-end=1000] [new-base=0] [label=idalloc] [check-curr-end=true] [2024/05/19 15:06:32.624 +08:00] [INFO] [util.go:50] ["load pd and cluster version"] [pd-version=8.2.0-alpha-37-g6ded6f8db] [cluster-version=0.0.0] [2024/05/19 15:06:32.624 +08:00] [INFO] [server.go:1806] ["PD leader is ready to serve"] [leader-name=pd3] [2024/05/19 15:06:32.919 +08:00] [INFO] [grpc_service.go:99] ["watch request"] [key=resource_group/settings] [range-end=resource_group/settingt] [start-revision=12] [2024/05/19 15:06:33.022 +08:00] [INFO] [grpc_service.go:99] ["watch request"] [key=/tidb/br-stream/info/] [range-end=/tidb/br-stream/info0] [start-revision=13] [2024/05/19 15:06:33.022 +08:00] [INFO] [grpc_service.go:99] ["watch request"] [key=/tidb/br-stream/pause/] [range-end=/tidb/br-stream/pause0] [start-revision=13] [2024/05/19 15:06:33.030 +08:00] [INFO] [server.go:712] ["try to bootstrap raft cluster"] [cluster-id=7370603652854261375] [request="header: store: region: peers: > "] [2024/05/19 15:06:33.030 +08:00] [INFO] [server.go:773] ["bootstrap cluster ok"] [cluster-id=7370603652854261375] [2024/05/19 15:06:33.033 +08:00] [INFO] [cluster.go:645] ["load stores"] [count=1] [cost=692.459µs] [2024/05/19 15:06:33.033 +08:00] [INFO] [cluster.go:656] ["load regions"] [count=1] [cost=213.276µs] [2024/05/19 15:06:33.034 +08:00] [INFO] [scheduling_controller.go:102] ["scheduling service is started"] [2024/05/19 15:06:33.034 +08:00] [INFO] [coordinator.go:404] ["coordinator starts to collect cluster information"] [2024/05/19 15:06:33.034 +08:00] [INFO] [cluster.go:418] ["memory info"] [total-mem=34359738368] [2024/05/19 15:06:33.034 +08:00] [INFO] [tuner.go:117] ["new gctuner"] [threshold=20615843020] [2024/05/19 15:06:33.034 +08:00] [INFO] [cluster.go:431] ["update gc tuner"] [enable-gc-tuner=false] [gc-threshold-bytes=20615843020] [2024/05/19 15:06:33.034 +08:00] [INFO] [memory_limit_tuner.go:50] [debug.SetMemoryLimit] [limit=9223372036854775807] [ret=9223372036854775807] [2024/05/19 15:06:33.034 +08:00] [INFO] [cluster.go:438] ["update gc memory limit"] [memory-limit-bytes=0] [memory-limit-gc-trigger-ratio=0.7] [2024/05/19 15:06:33.034 +08:00] [INFO] [keyspace.go:378] ["[keyspace] added region label for keyspace"] [keyspace-id=0] [label-rule="{\"id\":\"keyspaces/0\",\"index\":0,\"labels\":[{\"key\":\"id\",\"value\":\"0\"}],\"rule_type\":\"key-range\",\"data\":[{\"start_key\":\"7200000000000000fb\",\"end_key\":\"7200000100000000fb\"},{\"start_key\":\"7800000000000000fb\",\"end_key\":\"7800000100000000fb\"}]}"] [takes=471.933µs] [2024/05/19 15:06:33.037 +08:00] [INFO] [cluster.go:2191] ["cluster version changed"] [old-cluster-version=0.0.0] [new-cluster-version=8.2.0-alpha] [2024/05/19 15:06:33.038 +08:00] [INFO] [cluster.go:2280] ["store limit added"] [store-id=1] [2024/05/19 15:06:33.038 +08:00] [INFO] [grpc_service.go:835] ["put store ok"] [store="id:1 address:\"127.0.0.1:20180\" version:\"8.2.0-alpha\" peer_address:\"127.0.0.1:20180\" status_address:\"127.0.0.1:20185\" git_hash:\"a1a8672e93c4da9c2dc5704151314d92e1bc4c04\" start_timestamp:1716102393 deploy_path:\"/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/bin\" "] [2024/05/19 15:06:33.038 +08:00] [INFO] [util.go:50] ["load pd and cluster version"] [pd-version=8.2.0-alpha-37-g6ded6f8db] [cluster-version=8.2.0-alpha] [2024/05/19 15:06:33.618 +08:00] [INFO] [server.go:1320] ["PD server config is updated"] [new="{\"use-region-storage\":\"true\",\"max-gap-reset-ts\":\"24h0m0s\",\"key-type\":\"table\",\"runtime-services\":\"\",\"metric-storage\":\"\",\"dashboard-address\":\"http://127.0.0.1:2399\",\"flow-round-by-digit\":3,\"min-resolved-ts-persistence-interval\":\"1s\",\"server-memory-limit\":0,\"server-memory-limit-gc-trigger\":0.7,\"enable-gogc-tuner\":\"false\",\"gc-tuner-threshold\":0.6,\"block-safe-point-v1\":\"false\"}"] [old="{\"use-region-storage\":\"true\",\"max-gap-reset-ts\":\"24h0m0s\",\"key-type\":\"table\",\"runtime-services\":\"\",\"metric-storage\":\"\",\"dashboard-address\":\"auto\",\"flow-round-by-digit\":3,\"min-resolved-ts-persistence-interval\":\"1s\",\"server-memory-limit\":0,\"server-memory-limit-gc-trigger\":0.7,\"enable-gogc-tuner\":\"false\",\"gc-tuner-threshold\":0.6,\"block-safe-point-v1\":\"false\"}"] [2024/05/19 15:06:34.914 +08:00] [INFO] [dbstore.go:33] ["Dashboard initializing local storage file"] [path=/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pd3/dashboard.sqlite.db] [2024/05/19 15:06:34.967 +08:00] [INFO] [proxy.go:211] ["start serve requests to remotes"] [endpoint=127.0.0.1:45837] [remotes="[]"] [2024/05/19 15:06:34.967 +08:00] [INFO] [version.go:33] ["TiDB Dashboard started"] [internal-version=8.0.0-9768844f] [standalone=No] [pd-version=v8.2.0-alpha-37-g6ded6f8db] [build-time="2024-05-18 05:54:02"] [build-git-hash=9768844ff5d7] [2024/05/19 15:06:34.967 +08:00] [INFO] [manager.go:201] ["dashboard server is started"] [2024/05/19 15:06:34.967 +08:00] [INFO] [proxy.go:211] ["start serve requests to remotes"] [endpoint=127.0.0.1:35773] [remotes="[]"] [2024/05/19 15:06:34.968 +08:00] [WARN] [dynamic_config_manager.go:165] ["Dynamic config does not exist in etcd"] [2024/05/19 15:06:34.972 +08:00] [INFO] [manager.go:74] ["Key visual service is started"] [2024/05/19 15:06:36.034 +08:00] [INFO] [prepare_checker.go:72] ["not loaded from storage region number is satisfied, finish prepare checker"] [not-from-storage-region=1] [total-region=1] [2024/05/19 15:06:36.034 +08:00] [INFO] [coordinator.go:407] ["coordinator has finished cluster information preparation"] [2024/05/19 15:06:36.034 +08:00] [INFO] [coordinator.go:417] ["coordinator starts to run schedulers"] [2024/05/19 15:06:36.035 +08:00] [INFO] [coordinator.go:508] ["create scheduler"] [scheduler-name=balance-region-scheduler] [scheduler-args="[]"] [2024/05/19 15:06:36.035 +08:00] [INFO] [coordinator.go:508] ["create scheduler"] [scheduler-name=balance-leader-scheduler] [scheduler-args="[]"] [2024/05/19 15:06:36.036 +08:00] [INFO] [coordinator.go:508] ["create scheduler"] [scheduler-name=balance-hot-region-scheduler] [scheduler-args="[]"] [2024/05/19 15:06:36.036 +08:00] [INFO] [coordinator.go:508] ["create scheduler"] [scheduler-name=evict-slow-store-scheduler] [scheduler-args="[]"] [2024/05/19 15:06:36.037 +08:00] [INFO] [coordinator.go:533] ["scheduler config is updated"] [scheduler-config="[{\"type\":\"balance-region\",\"args\":null,\"disable\":false,\"args-payload\":\"\"},{\"type\":\"balance-leader\",\"args\":null,\"disable\":false,\"args-payload\":\"\"},{\"type\":\"hot-region\",\"args\":null,\"disable\":false,\"args-payload\":\"\"},{\"type\":\"evict-slow-store\",\"args\":null,\"disable\":false,\"args-payload\":\"\"}]"] [2024/05/19 15:06:36.037 +08:00] [INFO] [coordinator.go:165] ["coordinator starts patrol regions"] [2024/05/19 15:06:36.037 +08:00] [INFO] [coordinator.go:273] ["coordinator begins to check suspect key ranges"] [2024/05/19 15:06:36.037 +08:00] [INFO] [coordinator.go:337] ["coordinator begins to actively drive push operator"] [2024/05/19 15:06:36.047 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:06:36.047958915 +0800 CST m=+4.989912282, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:06:36.048 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:06:36.048 +08:00] [ERROR] [heartbeat_streams.go:141] ["send heartbeat message fail"] [region-id=2] [error="[PD:grpc:ErrGRPCSend]send request error: EOF"] [2024/05/19 15:06:37.037 +08:00] [INFO] [hot_region_config.go:490] ["query supported changed"] [last-query-support=false] [cluster-version=8.2.0-alpha] [config="{\"min-hot-byte-rate\":100,\"min-hot-key-rate\":10,\"min-hot-query-rate\":10,\"max-zombie-rounds\":3,\"max-peer-number\":1000,\"byte-rate-rank-step-ratio\":0.05,\"key-rate-rank-step-ratio\":0.05,\"query-rate-rank-step-ratio\":0.05,\"count-rank-step-ratio\":0.01,\"great-dec-ratio\":0.95,\"minor-dec-ratio\":0.99,\"src-tolerance-ratio\":1.05,\"dst-tolerance-ratio\":1.05,\"write-leader-priorities\":[\"query\",\"byte\"],\"write-peer-priorities\":[\"byte\",\"key\"],\"read-priorities\":[\"query\",\"byte\"],\"strict-picking-store\":\"true\",\"enable-for-tiflash\":\"true\",\"rank-formula-version\":\"v2\",\"forbid-rw-type\":\"none\",\"split-thresholds\":0.2,\"history-sample-duration\":\"5m0s\",\"history-sample-interval\":\"30s\"}"] [valid-config="{\"min-hot-byte-rate\":100,\"min-hot-key-rate\":10,\"min-hot-query-rate\":10,\"max-zombie-rounds\":3,\"max-peer-number\":1000,\"byte-rate-rank-step-ratio\":0.05,\"key-rate-rank-step-ratio\":0.05,\"query-rate-rank-step-ratio\":0.05,\"count-rank-step-ratio\":0.01,\"great-dec-ratio\":0.95,\"minor-dec-ratio\":0.99,\"src-tolerance-ratio\":1.05,\"dst-tolerance-ratio\":1.05,\"write-leader-priorities\":[\"key\",\"byte\"],\"write-peer-priorities\":[\"byte\",\"key\"],\"read-priorities\":[\"byte\",\"key\"],\"strict-picking-store\":\"true\",\"enable-for-tiflash\":\"true\",\"rank-formula-version\":\"v2\",\"split-thresholds\":0.2,\"history-sample-duration\":\"5m0s\",\"history-sample-interval\":\"30s\"}"] [2024/05/19 15:06:41.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:06:43.035 +08:00] [INFO] [cluster.go:1732] ["store has changed to serving"] [store-id=1] [store-address=127.0.0.1:20180] [2024/05/19 15:06:47.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:06:52.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:06:57.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:03.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:08.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:13.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:18.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:24.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:29.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:34.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:37.038 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.990007718s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:06:36.047958915 +0800 CST m=+4.989912282, startAt:2024-05-19 15:06:36.048057933 +0800 CST m=+4.990011299, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:07:37.041 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:07:37.041166445 +0800 CST m=+65.983119812, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:07:37.041 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:07:42.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:48.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:53.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:07:59.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:04.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:09.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:15.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:20.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:25.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:30.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:35.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:38.038 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.996787501s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:07:37.041166445 +0800 CST m=+65.983119812, startAt:2024-05-19 15:07:37.041257343 +0800 CST m=+65.983210710, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:08:38.047 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:08:38.047347233 +0800 CST m=+126.989300601, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:08:38.047 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:08:43.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:48.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:54.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:08:59.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:04.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:09.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:15.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:20.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:26.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:31.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:37.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:39.038 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.991191546s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:08:38.047347233 +0800 CST m=+126.989300601, startAt:2024-05-19 15:08:38.047450168 +0800 CST m=+126.989403535, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:09:39.042 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:09:39.042757874 +0800 CST m=+187.984711240, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:09:39.042 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:09:44.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:49.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:09:55.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:00.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:05.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:10.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:16.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:21.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:26.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:31.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:36.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:39.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.494786055s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:09:39.042757874 +0800 CST m=+187.984711240, startAt:2024-05-19 15:09:39.042847882 +0800 CST m=+187.984801249, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:10:39.546 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:10:39.546912748 +0800 CST m=+248.488866115, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:10:39.547 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:10:45.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:50.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:10:55.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:00.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:05.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:11.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:16.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:22.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:27.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:32.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:37.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:40.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.991234261s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:10:39.546912748 +0800 CST m=+248.488866115, startAt:2024-05-19 15:10:39.547022384 +0800 CST m=+248.488975750, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:11:40.541 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:11:40.541395251 +0800 CST m=+309.483348618, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:11:40.541 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:11:46.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:51.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:11:56.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:02.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:07.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:12.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:18.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:23.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:28.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:34.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:39.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:41.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.997017508s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:11:40.541395251 +0800 CST m=+309.483348618, startAt:2024-05-19 15:11:40.54148731 +0800 CST m=+309.483440677, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:12:41.541 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:12:41.541704197 +0800 CST m=+370.483657564, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:12:41.541 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:12:47.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:52.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:12:57.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:03.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:08.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:14.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:19.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:25.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:30.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:35.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:41.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:42.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.996006445s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:12:41.541704197 +0800 CST m=+370.483657564, startAt:2024-05-19 15:12:41.541781441 +0800 CST m=+370.483734808, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:13:42.538 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:13:42.538865155 +0800 CST m=+431.480818522, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:13:42.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:13:48.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:53.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:13:59.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:04.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:10.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:15.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:20.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:25.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:30.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:35.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:41.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:43.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.999720358s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:13:42.538865155 +0800 CST m=+431.480818522, startAt:2024-05-19 15:13:42.538956855 +0800 CST m=+431.480910222, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:14:43.544 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:14:43.544895483 +0800 CST m=+492.486848850, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:14:43.544 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:14:49.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:54.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:14:59.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:04.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:10.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:15.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:20.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:26.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:31.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:36.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:41.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:44.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.992957704s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:14:43.544895483 +0800 CST m=+492.486848850, startAt:2024-05-19 15:14:43.544973102 +0800 CST m=+492.486926469, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:15:44.545 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:15:44.545060937 +0800 CST m=+553.487014303, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:15:44.545 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:15:50.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:15:55.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:00.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:05.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:10.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:16.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:21.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:27.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:32.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:37.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:43.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:45.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.993498314s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:15:44.545060937 +0800 CST m=+553.487014303, startAt:2024-05-19 15:15:44.545154673 +0800 CST m=+553.487108040, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:16:45.540 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:16:45.540734562 +0800 CST m=+614.482687929, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:16:45.540 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:16:51.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:16:56.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:01.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:06.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:11.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:16.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:22.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:27.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:32.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:37.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:43.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:46.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.997020822s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:16:45.540734562 +0800 CST m=+614.482687929, startAt:2024-05-19 15:16:45.540805242 +0800 CST m=+614.482758610, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:17:46.538 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:17:46.538859721 +0800 CST m=+675.480813088, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:17:46.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:17:52.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:17:57.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:02.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:08.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:13.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:18.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:24.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:29.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:35.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:40.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:46.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:47.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.999225469s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:17:46.538859721 +0800 CST m=+675.480813088, startAt:2024-05-19 15:17:46.538962045 +0800 CST m=+675.480915412, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:18:47.543 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:18:47.543301097 +0800 CST m=+736.485254465, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:18:47.543 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:18:53.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:18:58.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:03.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:09.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:14.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:20.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:25.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:30.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:35.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:41.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:46.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:48.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.994614992s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:18:47.543301097 +0800 CST m=+736.485254465, startAt:2024-05-19 15:18:47.543385945 +0800 CST m=+736.485339313, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:19:48.544 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:19:48.544125631 +0800 CST m=+797.486078998, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:19:48.544 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:19:54.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:19:59.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:05.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:10.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:15.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:20.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:25.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:30.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:35.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:40.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:46.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:20:49.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.993411976s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:19:48.544125631 +0800 CST m=+797.486078998, startAt:2024-05-19 15:19:48.544214997 +0800 CST m=+797.486168364, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:20:49.547 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:20:49.546985743 +0800 CST m=+858.488939109, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:20:49.547 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:20:55.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:00.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:06.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:11.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:16.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:21.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:26.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:32.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:37.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:42.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:47.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:21:50.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.991153125s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:20:49.546985743 +0800 CST m=+858.488939109, startAt:2024-05-19 15:20:49.547059057 +0800 CST m=+858.489012424, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:21:50.543 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:21:50.543471967 +0800 CST m=+919.485425334, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:21:50.543 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:21:56.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:01.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:06.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:12.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:17.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:22.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:28.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:33.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:38.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:43.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:49.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:22:51.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.994908496s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:21:50.543471967 +0800 CST m=+919.485425334, startAt:2024-05-19 15:21:50.543582132 +0800 CST m=+919.485535498, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:22:51.545 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:22:51.545710426 +0800 CST m=+980.487663793, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:22:51.545 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:22:57.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:02.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:07.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:12.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:17.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:22.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:27.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:33.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:38.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:43.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:48.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:23:52.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.991865588s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:22:51.545710426 +0800 CST m=+980.487663793, startAt:2024-05-19 15:22:51.545790569 +0800 CST m=+980.487743936, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:23:52.540 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:23:52.540830384 +0800 CST m=+1041.482783751, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:23:52.540 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:23:58.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:03.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:08.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:14.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:19.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:24.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:29.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:35.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:40.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:45.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:50.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:24:53.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.997770005s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:23:52.540830384 +0800 CST m=+1041.482783751, startAt:2024-05-19 15:23:52.54092146 +0800 CST m=+1041.482874827, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:24:53.541 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:24:53.541842059 +0800 CST m=+1102.483795426, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:24:53.541 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:24:59.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:04.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:10.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:15.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:20.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:25.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:31.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:36.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:41.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:47.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:52.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:25:54.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.995936527s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:24:53.541842059 +0800 CST m=+1102.483795426, startAt:2024-05-19 15:24:53.541936677 +0800 CST m=+1102.483890043, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:25:54.538 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:25:54.538942859 +0800 CST m=+1163.480896225, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:25:54.539 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:26:00.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:05.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:10.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:15.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:20.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:25.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:31.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:36.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:41.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:46.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:52.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:26:55.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.998735417s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:25:54.538942859 +0800 CST m=+1163.480896225, startAt:2024-05-19 15:25:54.539034756 +0800 CST m=+1163.480988123, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:26:55.540 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:26:55.540903838 +0800 CST m=+1224.482857206, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:26:55.540 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:27:01.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:06.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:11.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:17.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:22.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:28.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:33.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:38.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:43.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:49.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:54.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:27:56.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.997110749s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:26:55.540903838 +0800 CST m=+1224.482857206, startAt:2024-05-19 15:26:55.540961957 +0800 CST m=+1224.482915323, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:27:56.541 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:27:56.541202579 +0800 CST m=+1285.483155945, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:27:56.541 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:28:02.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:07.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:13.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:18.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:23.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:29.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:34.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:39.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:45.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:50.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:56.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:28:57.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.996345355s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:27:56.541202579 +0800 CST m=+1285.483155945, startAt:2024-05-19 15:27:56.541299509 +0800 CST m=+1285.483252877, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:28:57.543 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:28:57.543785157 +0800 CST m=+1346.485738525, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:28:57.543 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:29:03.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:08.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:14.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:19.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:24.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:30.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:35.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:40.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:45.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:51.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:56.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:29:58.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.993835239s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:28:57.543785157 +0800 CST m=+1346.485738525, startAt:2024-05-19 15:28:57.543857169 +0800 CST m=+1346.485810536, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:29:58.538 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:29:58.538788744 +0800 CST m=+1407.480742111, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:29:58.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:30:04.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:09.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:14.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:19.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:24.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:29.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:35.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:40.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:45.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:50.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:56.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:30:59.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.999793901s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:29:58.538788744 +0800 CST m=+1407.480742111, startAt:2024-05-19 15:29:58.538878438 +0800 CST m=+1407.480831805, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:30:59.540 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:30:59.540820968 +0800 CST m=+1468.482774335, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:30:59.540 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:31:05.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:10.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:16.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:21.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:26.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:31.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:36.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:42.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:47.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:52.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:31:57.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:00.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.997482548s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:30:59.540820968 +0800 CST m=+1468.482774335, startAt:2024-05-19 15:30:59.540899702 +0800 CST m=+1468.482853068, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:32:00.543 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:32:00.543615987 +0800 CST m=+1529.485569354, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:32:00.543 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:32:06.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:11.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:16.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:22.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:27.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:33.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:38.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:43.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:48.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:53.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:32:59.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:01.537 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.994200138s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:32:00.543615987 +0800 CST m=+1529.485569354, startAt:2024-05-19 15:32:00.543735783 +0800 CST m=+1529.485689150, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:33:01.544 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:33:01.544088988 +0800 CST m=+1590.486042392, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:33:01.544 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:33:07.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:12.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:18.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:23.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:28.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:33.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:38.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:44.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:49.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:33:54.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:00.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:02.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.994225999s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:33:01.544088988 +0800 CST m=+1590.486042392, startAt:2024-05-19 15:33:01.544179376 +0800 CST m=+1590.486132749, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:34:02.545 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:34:02.545534703 +0800 CST m=+1651.487488073, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:34:02.545 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:34:08.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:13.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:18.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:24.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:29.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:34.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:39.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:45.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:50.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:34:55.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:00.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:03.538 +08:00] [INFO] [operator_controller.go:718] ["operator timeout"] [region-id=2] [takes=1m0.992966644s] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:34:02.545534703 +0800 CST m=+1651.487488073, startAt:2024-05-19 15:34:02.545629608 +0800 CST m=+1651.487582975, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s]) timeout\""] [additional-info="{\"cancel-reason\":\"timeout\",\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:35:03.544 +08:00] [INFO] [operator_controller.go:510] ["add operator"] [region-id=2] [operator="\"labeler-split-region {split: region 2 use policy USEKEY and keys [7200000000000000FB 7200000100000000FB 7800000000000000FB 7800000100000000FB]} (kind:split, region:2(1, 1), createAt:2024-05-19 15:35:03.54472201 +0800 CST m=+1712.486675376, startAt:0001-01-01 00:00:00 +0000 UTC, currentStep:0, size:0, steps:[0:{split region with policy USEKEY}], timeout:[1m0s])\""] [additional-info="{\"region-end-key\":\"\",\"region-start-key\":\"\"}"] [2024/05/19 15:35:03.544 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source=create] [2024/05/19 15:35:09.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:14.037 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:19.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:24.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:29.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:34.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:40.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:45.537 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:50.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:35:56.038 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"] [2024/05/19 15:36:01.538 +08:00] [INFO] [operator_controller.go:786] ["send schedule command"] [region-id=2] [step="split region with policy USEKEY"] [source="active push"]