{"level":"warn","ts":"2026-10-09T06:43:12.436419Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T06:43:12.437009Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.66:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.66:2380","--initial-cluster=instance=https://162.253.55.66:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.66:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.66:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-09T06:43:12.437127Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T06:43:12.437144Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.66:2380"]} {"level":"info","ts":"2026-10-09T06:43:12.437164Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-09T06:43:12.438328Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"]} {"level":"info","ts":"2026-10-09T06:43:12.438492Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.66:2380"],"listen-peer-urls":["https://162.253.55.66:2380"],"advertise-client-urls":["https://162.253.55.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.66:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-09T06:43:12.443633Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.893346ms"} {"level":"info","ts":"2026-10-09T06:43:12.455118Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"48afe2e91b68f008","cluster-id":"5894dcc7313657d8"} {"level":"info","ts":"2026-10-09T06:43:12.455284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T06:43:12.455308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became follower at term 0"} {"level":"info","ts":"2026-10-09T06:43:12.455355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 48afe2e91b68f008 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T06:43:12.455365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became follower at term 1"} {"level":"info","ts":"2026-10-09T06:43:12.455433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=(5237654382470295560)"} {"level":"warn","ts":"2026-10-09T06:43:12.467958Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T06:43:12.469839Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T06:43:12.471617Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-09T06:43:12.473793Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"48afe2e91b68f008","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T06:43:12.473931Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"48afe2e91b68f008","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T06:43:12.4742Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T06:43:12.474307Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T06:43:12.474342Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T06:43:12.486008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=(5237654382470295560)"} {"level":"info","ts":"2026-10-09T06:43:12.486162Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5894dcc7313657d8","local-member-id":"48afe2e91b68f008","added-peer-id":"48afe2e91b68f008","added-peer-peer-urls":["https://162.253.55.66:2380"]} {"level":"info","ts":"2026-10-09T06:43:12.486604Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-09T06:43:12.486658Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.66:2380"} {"level":"info","ts":"2026-10-09T06:43:12.486733Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.66:2380"} {"level":"info","ts":"2026-10-09T06:43:12.486859Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"48afe2e91b68f008","initial-advertise-peer-urls":["https://162.253.55.66:2380"],"listen-peer-urls":["https://162.253.55.66:2380"],"advertise-client-urls":["https://162.253.55.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T06:43:12.486889Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T06:43:13.256523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T06:43:13.256613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T06:43:13.256646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 received MsgPreVoteResp from 48afe2e91b68f008 at term 1"} {"level":"info","ts":"2026-10-09T06:43:13.256664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became candidate at term 2"} {"level":"info","ts":"2026-10-09T06:43:13.256681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 received MsgVoteResp from 48afe2e91b68f008 at term 2"} {"level":"info","ts":"2026-10-09T06:43:13.256696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became leader at term 2"} {"level":"info","ts":"2026-10-09T06:43:13.256708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 48afe2e91b68f008 elected leader 48afe2e91b68f008 at term 2"} {"level":"info","ts":"2026-10-09T06:43:13.257877Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T06:43:13.258848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T06:43:13.258842Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"48afe2e91b68f008","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.66:2379]}","request-path":"/0/members/48afe2e91b68f008/attributes","cluster-id":"5894dcc7313657d8","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T06:43:13.259026Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T06:43:13.259263Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T06:43:13.25935Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T06:43:13.25956Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5894dcc7313657d8","local-member-id":"48afe2e91b68f008","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T06:43:13.259706Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T06:43:13.259761Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T06:43:13.261925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.66:2379"} {"level":"info","ts":"2026-10-09T06:43:13.262025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T06:43:17.691832Z","caller":"traceutil/trace.go:171","msg":"trace[1930869707] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"249.637527ms","start":"2026-10-09T06:43:17.442172Z","end":"2026-10-09T06:43:17.69181Z","steps":["trace[1930869707] 'process raft request' (duration: 249.529454ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:44:00.744139Z","caller":"traceutil/trace.go:171","msg":"trace[1045403488] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"140.849338ms","start":"2026-10-09T06:44:00.603245Z","end":"2026-10-09T06:44:00.744094Z","steps":["trace[1045403488] 'process raft request' (duration: 140.272788ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:44:01.175577Z","caller":"traceutil/trace.go:171","msg":"trace[1234495174] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"261.981646ms","start":"2026-10-09T06:44:00.91356Z","end":"2026-10-09T06:44:01.175542Z","steps":["trace[1234495174] 'process raft request' (duration: 233.12083ms)","trace[1234495174] 'compare' (duration: 28.329098ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:44:01.661179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.734049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-09T06:44:01.661447Z","caller":"traceutil/trace.go:171","msg":"trace[1155556553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"151.116575ms","start":"2026-10-09T06:44:01.5103Z","end":"2026-10-09T06:44:01.661416Z","steps":["trace[1155556553] 'range keys from in-memory index tree' (duration: 150.444765ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:44:01.663483Z","caller":"traceutil/trace.go:171","msg":"trace[1130461060] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"140.966591ms","start":"2026-10-09T06:44:01.52249Z","end":"2026-10-09T06:44:01.663456Z","steps":["trace[1130461060] 'process raft request' (duration: 140.070007ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:44:16.228815Z","caller":"traceutil/trace.go:171","msg":"trace[1663439539] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"229.622028ms","start":"2026-10-09T06:44:15.999158Z","end":"2026-10-09T06:44:16.22878Z","steps":["trace[1663439539] 'process raft request' (duration: 229.359834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:45:03.049124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.784625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T06:45:03.04921Z","caller":"traceutil/trace.go:171","msg":"trace[1270858979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1352; }","duration":"100.903096ms","start":"2026-10-09T06:45:02.948287Z","end":"2026-10-09T06:45:03.04919Z","steps":["trace[1270858979] 'range keys from in-memory index tree' (duration: 100.731814ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:45:48.29248Z","caller":"traceutil/trace.go:171","msg":"trace[342982070] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"119.122931ms","start":"2026-10-09T06:45:48.173331Z","end":"2026-10-09T06:45:48.292454Z","steps":["trace[342982070] 'process raft request' (duration: 118.917089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:45:48.363486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.273877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-09T06:45:48.363555Z","caller":"traceutil/trace.go:171","msg":"trace[618161107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1999; }","duration":"136.376429ms","start":"2026-10-09T06:45:48.227162Z","end":"2026-10-09T06:45:48.363539Z","steps":["trace[618161107] 'agreement among raft nodes before linearized reading' (duration: 136.147806ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:46:14.740568Z","caller":"traceutil/trace.go:171","msg":"trace[14452992] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2246; }","duration":"123.422724ms","start":"2026-10-09T06:46:14.617126Z","end":"2026-10-09T06:46:14.740549Z","steps":["trace[14452992] 'read index received' (duration: 123.28108ms)","trace[14452992] 'applied index is now lower than readState.Index' (duration: 141.094µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:46:14.740651Z","caller":"traceutil/trace.go:171","msg":"trace[1853580416] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"143.231467ms","start":"2026-10-09T06:46:14.597389Z","end":"2026-10-09T06:46:14.74062Z","steps":["trace[1853580416] 'process raft request' (duration: 143.056903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:46:14.740712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.560257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T06:46:14.740886Z","caller":"traceutil/trace.go:171","msg":"trace[491517225] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2159; }","duration":"123.757723ms","start":"2026-10-09T06:46:14.617095Z","end":"2026-10-09T06:46:14.740852Z","steps":["trace[491517225] 'agreement among raft nodes before linearized reading' (duration: 123.531517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:46:15.062622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.103374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T06:46:15.062829Z","caller":"traceutil/trace.go:171","msg":"trace[1139022056] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"275.715366ms","start":"2026-10-09T06:46:14.787038Z","end":"2026-10-09T06:46:15.062754Z","steps":["trace[1139022056] 'process raft request' (duration: 51.877742ms)","trace[1139022056] 'compare' (duration: 222.899899ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:46:15.063568Z","caller":"traceutil/trace.go:171","msg":"trace[1658839577] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2247; }","duration":"246.116813ms","start":"2026-10-09T06:46:14.817373Z","end":"2026-10-09T06:46:15.06349Z","steps":["trace[1658839577] 'read index received' (duration: 21.488999ms)","trace[1658839577] 'applied index is now lower than readState.Index' (duration: 224.621844ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:46:15.063747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.340369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T06:46:15.063785Z","caller":"traceutil/trace.go:171","msg":"trace[1038116877] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2161; }","duration":"246.43351ms","start":"2026-10-09T06:46:14.817341Z","end":"2026-10-09T06:46:15.063774Z","steps":["trace[1038116877] 'agreement among raft nodes before linearized reading' (duration: 246.316498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:46:15.98823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.826299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-09T06:46:15.988355Z","caller":"traceutil/trace.go:171","msg":"trace[1832007445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2162; }","duration":"122.005143ms","start":"2026-10-09T06:46:15.866305Z","end":"2026-10-09T06:46:15.98831Z","steps":["trace[1832007445] 'range keys from in-memory index tree' (duration: 121.613613ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:47:44.932026Z","caller":"traceutil/trace.go:171","msg":"trace[1854992423] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"127.208536ms","start":"2026-10-09T06:47:44.804779Z","end":"2026-10-09T06:47:44.931987Z","steps":["trace[1854992423] 'process raft request' (duration: 97.064271ms)","trace[1854992423] 'compare' (duration: 30.026022ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:51:59.67954Z","caller":"traceutil/trace.go:171","msg":"trace[1219024443] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"100.259824ms","start":"2026-10-09T06:51:59.579256Z","end":"2026-10-09T06:51:59.679516Z","steps":["trace[1219024443] 'process raft request' (duration: 46.819145ms)","trace[1219024443] 'compare' (duration: 53.315558ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:52:04.421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.767419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T06:52:04.421078Z","caller":"traceutil/trace.go:171","msg":"trace[1569312194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4871; }","duration":"218.91706ms","start":"2026-10-09T06:52:04.202142Z","end":"2026-10-09T06:52:04.421059Z","steps":["trace[1569312194] 'range keys from in-memory index tree' (duration: 218.687048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:52:04.421296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.624028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-09T06:52:04.421324Z","caller":"traceutil/trace.go:171","msg":"trace[2098025072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4871; }","duration":"216.651298ms","start":"2026-10-09T06:52:04.204663Z","end":"2026-10-09T06:52:04.421314Z","steps":["trace[2098025072] 'range keys from in-memory index tree' (duration: 216.512247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:52:04.421551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.184543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T06:52:04.421578Z","caller":"traceutil/trace.go:171","msg":"trace[1900125314] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4871; }","duration":"209.267925ms","start":"2026-10-09T06:52:04.212302Z","end":"2026-10-09T06:52:04.421569Z","steps":["trace[1900125314] 'range keys from in-memory index tree' (duration: 209.089163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:52:04.421754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.466673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2bcc6c5b-6dfc-43f5-9e79-3ee6283f6881\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-09T06:52:04.421788Z","caller":"traceutil/trace.go:171","msg":"trace[1866600754] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2bcc6c5b-6dfc-43f5-9e79-3ee6283f6881; range_end:; response_count:1; response_revision:4871; }","duration":"180.539713ms","start":"2026-10-09T06:52:04.24124Z","end":"2026-10-09T06:52:04.42178Z","steps":["trace[1866600754] 'range keys from in-memory index tree' (duration: 180.331881ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:04.483551Z","caller":"traceutil/trace.go:171","msg":"trace[259663572] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"108.391846ms","start":"2026-10-09T06:52:04.375133Z","end":"2026-10-09T06:52:04.483525Z","steps":["trace[259663572] 'process raft request' (duration: 108.233424ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:04.53557Z","caller":"traceutil/trace.go:171","msg":"trace[328110286] linearizableReadLoop","detail":"{readStateIndex:5059; appliedIndex:5057; }","duration":"112.276109ms","start":"2026-10-09T06:52:04.423269Z","end":"2026-10-09T06:52:04.535545Z","steps":["trace[328110286] 'read index received' (duration: 60.17059ms)","trace[328110286] 'applied index is now lower than readState.Index' (duration: 52.104539ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:52:04.535902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.611543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T06:52:04.535946Z","caller":"traceutil/trace.go:171","msg":"trace[291158694] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"104.370308ms","start":"2026-10-09T06:52:04.431563Z","end":"2026-10-09T06:52:04.535933Z","steps":["trace[291158694] 'process raft request' (duration: 103.923502ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:04.535981Z","caller":"traceutil/trace.go:171","msg":"trace[444430453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4874; }","duration":"112.726086ms","start":"2026-10-09T06:52:04.423237Z","end":"2026-10-09T06:52:04.535963Z","steps":["trace[444430453] 'agreement among raft nodes before linearized reading' (duration: 112.596345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:52:04.535967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.812034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T06:52:04.536069Z","caller":"traceutil/trace.go:171","msg":"trace[1874605886] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4874; }","duration":"109.943727ms","start":"2026-10-09T06:52:04.426099Z","end":"2026-10-09T06:52:04.536043Z","steps":["trace[1874605886] 'agreement among raft nodes before linearized reading' (duration: 109.778394ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:04.536425Z","caller":"traceutil/trace.go:171","msg":"trace[74831723] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"109.596303ms","start":"2026-10-09T06:52:04.426019Z","end":"2026-10-09T06:52:04.535615Z","steps":["trace[74831723] 'process raft request' (duration: 109.319028ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:04.75365Z","caller":"traceutil/trace.go:171","msg":"trace[1867801278] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"106.386777ms","start":"2026-10-09T06:52:04.64723Z","end":"2026-10-09T06:52:04.753617Z","steps":["trace[1867801278] 'process raft request' (duration: 21.590722ms)","trace[1867801278] 'compare' (duration: 84.625703ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:52:04.849114Z","caller":"traceutil/trace.go:171","msg":"trace[838014623] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"109.787274ms","start":"2026-10-09T06:52:04.73929Z","end":"2026-10-09T06:52:04.849077Z","steps":["trace[838014623] 'process raft request' (duration: 109.387099ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:18.825293Z","caller":"traceutil/trace.go:171","msg":"trace[17152291] linearizableReadLoop","detail":"{readStateIndex:5262; appliedIndex:5261; }","duration":"277.8809ms","start":"2026-10-09T06:52:18.547385Z","end":"2026-10-09T06:52:18.825266Z","steps":["trace[17152291] 'read index received' (duration: 245.4748ms)","trace[17152291] 'applied index is now lower than readState.Index' (duration: 32.40502ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:52:18.825312Z","caller":"traceutil/trace.go:171","msg":"trace[1297917321] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"285.13097ms","start":"2026-10-09T06:52:18.540142Z","end":"2026-10-09T06:52:18.825273Z","steps":["trace[1297917321] 'process raft request' (duration: 252.831872ms)","trace[1297917321] 'compare' (duration: 32.143316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:52:18.825572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.158874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-10-09T06:52:18.825604Z","caller":"traceutil/trace.go:171","msg":"trace[1628351578] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5069; }","duration":"278.253555ms","start":"2026-10-09T06:52:18.547341Z","end":"2026-10-09T06:52:18.825594Z","steps":["trace[1628351578] 'agreement among raft nodes before linearized reading' (duration: 278.074421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:52:18.825703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.100962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2bcc6c5b-6dfc-43f5-9e79-3ee6283f6881\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-09T06:52:18.825746Z","caller":"traceutil/trace.go:171","msg":"trace[1191305670] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2bcc6c5b-6dfc-43f5-9e79-3ee6283f6881; range_end:; response_count:1; response_revision:5069; }","duration":"278.169673ms","start":"2026-10-09T06:52:18.547558Z","end":"2026-10-09T06:52:18.825728Z","steps":["trace[1191305670] 'agreement among raft nodes before linearized reading' (duration: 278.011031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:52:18.825837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.715064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T06:52:18.825904Z","caller":"traceutil/trace.go:171","msg":"trace[899688373] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5069; }","duration":"186.854125ms","start":"2026-10-09T06:52:18.639034Z","end":"2026-10-09T06:52:18.825888Z","steps":["trace[899688373] 'agreement among raft nodes before linearized reading' (duration: 186.730784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:52:18.825974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.230795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T06:52:18.826004Z","caller":"traceutil/trace.go:171","msg":"trace[1172480463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5069; }","duration":"227.280707ms","start":"2026-10-09T06:52:18.598714Z","end":"2026-10-09T06:52:18.825995Z","steps":["trace[1172480463] 'agreement among raft nodes before linearized reading' (duration: 227.189405ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:19.052821Z","caller":"traceutil/trace.go:171","msg":"trace[1783642222] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"161.359201ms","start":"2026-10-09T06:52:18.891427Z","end":"2026-10-09T06:52:19.052787Z","steps":["trace[1783642222] 'process raft request' (duration: 92.374483ms)","trace[1783642222] 'compare' (duration: 68.771215ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:52:19.05283Z","caller":"traceutil/trace.go:171","msg":"trace[1530924182] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"161.167398ms","start":"2026-10-09T06:52:18.891641Z","end":"2026-10-09T06:52:19.052808Z","steps":["trace[1530924182] 'process raft request' (duration: 161.063586ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:19.052994Z","caller":"traceutil/trace.go:171","msg":"trace[1137793521] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"159.341523ms","start":"2026-10-09T06:52:18.89363Z","end":"2026-10-09T06:52:19.052972Z","steps":["trace[1137793521] 'process raft request' (duration: 159.13711ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:52:19.05324Z","caller":"traceutil/trace.go:171","msg":"trace[416488939] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"149.440675ms","start":"2026-10-09T06:52:18.903765Z","end":"2026-10-09T06:52:19.053206Z","steps":["trace[416488939] 'process raft request' (duration: 149.139492ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:53:03.120854Z","caller":"traceutil/trace.go:171","msg":"trace[329065932] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"117.382094ms","start":"2026-10-09T06:53:03.003442Z","end":"2026-10-09T06:53:03.120824Z","steps":["trace[329065932] 'process raft request' (duration: 116.822266ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:53:13.298902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2906} {"level":"info","ts":"2026-10-09T06:53:13.408801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2906,"took":"108.6629ms","hash":299328290,"current-db-size-bytes":53948416,"current-db-size":"54 MB","current-db-size-in-use-bytes":28622848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T06:53:13.409045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":299328290,"revision":2906,"compact-revision":-1} {"level":"info","ts":"2026-10-09T06:58:13.306392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5510} {"level":"info","ts":"2026-10-09T06:58:13.434249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5510,"took":"126.253757ms","hash":751546068,"current-db-size-bytes":53948416,"current-db-size":"54 MB","current-db-size-in-use-bytes":18550784,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-09T06:58:13.4343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":751546068,"revision":5510,"compact-revision":2906}