{"level":"warn","ts":"2026-09-18T22:55:01.110512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T22:55:01.110621Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.104: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.104:2380","--initial-cluster=instance=https://162.253.55.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.104: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-09-18T22:55:01.110696Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T22:55:01.110708Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-09-18T22:55:01.110728Z","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-09-18T22:55:01.111486Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"]} {"level":"info","ts":"2026-09-18T22:55:01.111583Z","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.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.104: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-09-18T22:55:01.146592Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"34.747799ms"} {"level":"info","ts":"2026-09-18T22:55:01.223828Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8572cc3a5351216d","cluster-id":"c1a156b8f69e6a79"} {"level":"info","ts":"2026-09-18T22:55:01.224066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T22:55:01.22421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 0"} {"level":"info","ts":"2026-09-18T22:55:01.224309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8572cc3a5351216d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T22:55:01.224381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 1"} {"level":"info","ts":"2026-09-18T22:55:01.224485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"warn","ts":"2026-09-18T22:55:01.245026Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T22:55:01.252353Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T22:55:01.254554Z","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-09-18T22:55:01.258228Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8572cc3a5351216d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T22:55:01.258364Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8572cc3a5351216d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T22:55:01.258469Z","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-09-18T22:55:01.258531Z","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-09-18T22:55:01.258545Z","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-09-18T22:55:01.260696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"info","ts":"2026-09-18T22:55:01.261184Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","added-peer-id":"8572cc3a5351216d","added-peer-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-09-18T22:55:01.261837Z","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-09-18T22:55:01.261871Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-09-18T22:55:01.262044Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-09-18T22:55:01.262681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8572cc3a5351216d","initial-advertise-peer-urls":["https://162.253.55.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T22:55:01.262737Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T22:55:01.525642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T22:55:01.525697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T22:55:01.525732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgPreVoteResp from 8572cc3a5351216d at term 1"} {"level":"info","ts":"2026-09-18T22:55:01.52575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became candidate at term 2"} {"level":"info","ts":"2026-09-18T22:55:01.52576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgVoteResp from 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-09-18T22:55:01.525774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became leader at term 2"} {"level":"info","ts":"2026-09-18T22:55:01.525785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8572cc3a5351216d elected leader 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-09-18T22:55:01.530699Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T22:55:01.535055Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8572cc3a5351216d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.104:2379]}","request-path":"/0/members/8572cc3a5351216d/attributes","cluster-id":"c1a156b8f69e6a79","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T22:55:01.535286Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T22:55:01.535289Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T22:55:01.535365Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T22:55:01.535411Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T22:55:01.536801Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T22:55:01.536959Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T22:55:01.537002Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T22:55:01.538137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.104:2379"} {"level":"info","ts":"2026-09-18T22:55:01.538701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-18T22:55:04.159381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.69994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T22:55:04.159491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.400033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T22:55:04.159514Z","caller":"traceutil/trace.go:171","msg":"trace[1094171004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"161.879324ms","start":"2026-09-18T22:55:03.997609Z","end":"2026-09-18T22:55:04.159488Z","steps":["trace[1094171004] 'range keys from in-memory index tree' (duration: 161.561996ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:04.15955Z","caller":"traceutil/trace.go:171","msg":"trace[1317508409] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:72; }","duration":"190.508496ms","start":"2026-09-18T22:55:03.969026Z","end":"2026-09-18T22:55:04.159534Z","steps":["trace[1317508409] 'range keys from in-memory index tree' (duration: 190.322481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:04.159536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.572207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T22:55:04.159936Z","caller":"traceutil/trace.go:171","msg":"trace[1762007812] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:72; }","duration":"191.045668ms","start":"2026-09-18T22:55:03.968858Z","end":"2026-09-18T22:55:04.159904Z","steps":["trace[1762007812] 'range keys from in-memory index tree' (duration: 190.446544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:05.918844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.656574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T22:55:05.918957Z","caller":"traceutil/trace.go:171","msg":"trace[2078126234] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"120.99681ms","start":"2026-09-18T22:55:05.797937Z","end":"2026-09-18T22:55:05.918934Z","steps":["trace[2078126234] 'process raft request' (duration: 18.603577ms)","trace[2078126234] 'compare' (duration: 101.445249ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:55:11.6935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.380611ms","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-09-18T22:55:11.693593Z","caller":"traceutil/trace.go:171","msg":"trace[1101200951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:346; }","duration":"236.540844ms","start":"2026-09-18T22:55:11.457032Z","end":"2026-09-18T22:55:11.693573Z","steps":["trace[1101200951] 'range keys from in-memory index tree' (duration: 236.207687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.693889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.119504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-09-18T22:55:11.693916Z","caller":"traceutil/trace.go:171","msg":"trace[1483379042] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:346; }","duration":"607.204886ms","start":"2026-09-18T22:55:11.086703Z","end":"2026-09-18T22:55:11.693908Z","steps":["trace[1483379042] 'range keys from in-memory index tree' (duration: 607.022212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.693945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.086674Z","time spent":"607.260107ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-09-18T22:55:11.6941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.089482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-18T22:55:11.694124Z","caller":"traceutil/trace.go:171","msg":"trace[707844024] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:346; }","duration":"603.132553ms","start":"2026-09-18T22:55:11.090983Z","end":"2026-09-18T22:55:11.694116Z","steps":["trace[707844024] 'range keys from in-memory index tree' (duration: 602.98516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.694182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.090974Z","time spent":"603.201685ms","remote":"127.0.0.1:51242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-18T22:55:11.69433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.00323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-tao6tr\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-09-18T22:55:11.694354Z","caller":"traceutil/trace.go:171","msg":"trace[1647254972] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-tao6tr; range_end:; response_count:1; response_revision:346; }","duration":"603.030681ms","start":"2026-09-18T22:55:11.091316Z","end":"2026-09-18T22:55:11.694347Z","steps":["trace[1647254972] 'range keys from in-memory index tree' (duration: 602.887848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.694377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.091227Z","time spent":"603.143393ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":564,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-tao6tr\" "} {"level":"warn","ts":"2026-09-18T22:55:11.694539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.630235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-18T22:55:11.694652Z","caller":"traceutil/trace.go:171","msg":"trace[1824166801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:346; }","duration":"397.816009ms","start":"2026-09-18T22:55:11.296811Z","end":"2026-09-18T22:55:11.694627Z","steps":["trace[1824166801] 'range keys from in-memory index tree' (duration: 397.410838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.694638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.023863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-18T22:55:11.694769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.663118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-18T22:55:11.694704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.296792Z","time spent":"397.90067ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T22:55:11.694827Z","caller":"traceutil/trace.go:171","msg":"trace[1091954780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:346; }","duration":"468.757721ms","start":"2026-09-18T22:55:11.226055Z","end":"2026-09-18T22:55:11.694813Z","steps":["trace[1091954780] 'range keys from in-memory index tree' (duration: 468.548825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.694851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.517043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-18T22:55:11.694867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.226036Z","time spent":"468.819813ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T22:55:11.694881Z","caller":"traceutil/trace.go:171","msg":"trace[1120508828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:346; }","duration":"434.620175ms","start":"2026-09-18T22:55:11.26025Z","end":"2026-09-18T22:55:11.69487Z","steps":["trace[1120508828] 'range keys from in-memory index tree' (duration: 434.38046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.694873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.744579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-09-18T22:55:11.69491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.260221Z","time spent":"434.682287ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T22:55:11.694934Z","caller":"traceutil/trace.go:171","msg":"trace[1029176062] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:346; }","duration":"357.84505ms","start":"2026-09-18T22:55:11.337074Z","end":"2026-09-18T22:55:11.694919Z","steps":["trace[1029176062] 'range keys from in-memory index tree' (duration: 357.647456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.694978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.337059Z","time spent":"357.904172ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-09-18T22:55:11.695007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.342218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-09-18T22:55:11.695048Z","caller":"traceutil/trace.go:171","msg":"trace[687012615] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:346; }","duration":"555.4241ms","start":"2026-09-18T22:55:11.139611Z","end":"2026-09-18T22:55:11.695035Z","steps":["trace[687012615] 'range keys from in-memory index tree' (duration: 555.229495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.695085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.139588Z","time spent":"555.485962ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-09-18T22:55:11.695086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.799694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-18T22:55:11.695153Z","caller":"traceutil/trace.go:171","msg":"trace[413765569] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:346; }","duration":"406.897996ms","start":"2026-09-18T22:55:11.288219Z","end":"2026-09-18T22:55:11.695117Z","steps":["trace[413765569] 'range keys from in-memory index tree' (duration: 406.714712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.695182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.288179Z","time spent":"406.995298ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-09-18T22:55:11.695224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.409698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-09-18T22:55:11.695269Z","caller":"traceutil/trace.go:171","msg":"trace[255302117] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:346; }","duration":"507.505641ms","start":"2026-09-18T22:55:11.187751Z","end":"2026-09-18T22:55:11.695256Z","steps":["trace[255302117] 'range keys from in-memory index tree' (duration: 507.327867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.695278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.964337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-09-18T22:55:11.695305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.187714Z","time spent":"507.580103ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-09-18T22:55:11.695316Z","caller":"traceutil/trace.go:171","msg":"trace[262773729] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:346; }","duration":"308.08529ms","start":"2026-09-18T22:55:11.387219Z","end":"2026-09-18T22:55:11.695304Z","steps":["trace[262773729] 'range keys from in-memory index tree' (duration: 307.742752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.695362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.387115Z","time spent":"308.236093ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-09-18T22:55:11.694771Z","caller":"traceutil/trace.go:171","msg":"trace[319535426] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:346; }","duration":"258.229907ms","start":"2026-09-18T22:55:11.436513Z","end":"2026-09-18T22:55:11.694743Z","steps":["trace[319535426] 'range keys from in-memory index tree' (duration: 257.887949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.694766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-18T22:55:11.695427Z","caller":"traceutil/trace.go:171","msg":"trace[1693160595] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:346; }","duration":"458.430526ms","start":"2026-09-18T22:55:11.236978Z","end":"2026-09-18T22:55:11.695409Z","steps":["trace[1693160595] 'range keys from in-memory index tree' (duration: 457.692168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.695479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.236968Z","time spent":"458.495107ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-09-18T22:55:11.97632Z","caller":"traceutil/trace.go:171","msg":"trace[1124732464] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"274.862455ms","start":"2026-09-18T22:55:11.701414Z","end":"2026-09-18T22:55:11.976277Z","steps":["trace[1124732464] 'read index received' (duration: 274.397314ms)","trace[1124732464] 'applied index is now lower than readState.Index' (duration: 463.631µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:55:11.976535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.082539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-09-18T22:55:11.97658Z","caller":"traceutil/trace.go:171","msg":"trace[698000023] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:347; }","duration":"275.190622ms","start":"2026-09-18T22:55:11.701375Z","end":"2026-09-18T22:55:11.976565Z","steps":["trace[698000023] 'agreement among raft nodes before linearized reading' (duration: 275.065559ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:11.976394Z","caller":"traceutil/trace.go:171","msg":"trace[1832235261] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"275.372886ms","start":"2026-09-18T22:55:11.70097Z","end":"2026-09-18T22:55:11.976343Z","steps":["trace[1832235261] 'process raft request' (duration: 275.025748ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:12.074889Z","caller":"traceutil/trace.go:171","msg":"trace[1952639705] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"371.522126ms","start":"2026-09-18T22:55:11.703336Z","end":"2026-09-18T22:55:12.074858Z","steps":["trace[1952639705] 'process raft request' (duration: 356.566679ms)","trace[1952639705] 'compare' (duration: 14.579018ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:55:12.075383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.703314Z","time spent":"371.666199ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:55:12.075472Z","caller":"traceutil/trace.go:171","msg":"trace[1293283157] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"371.636628ms","start":"2026-09-18T22:55:11.703799Z","end":"2026-09-18T22:55:12.075436Z","steps":["trace[1293283157] 'process raft request' (duration: 370.975094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.075666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.703784Z","time spent":"371.74929ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:55:12.076469Z","caller":"traceutil/trace.go:171","msg":"trace[617972638] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"369.758826ms","start":"2026-09-18T22:55:11.706681Z","end":"2026-09-18T22:55:12.07644Z","steps":["trace[617972638] 'process raft request' (duration: 368.781503ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:12.07665Z","caller":"traceutil/trace.go:171","msg":"trace[1738497197] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"369.614673ms","start":"2026-09-18T22:55:11.706921Z","end":"2026-09-18T22:55:12.076536Z","steps":["trace[1738497197] 'process raft request' (duration: 369.257355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.076814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.706894Z","time spent":"369.845178ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T22:55:12.076713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.70666Z","time spent":"369.92467ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T22:55:12.07718Z","caller":"traceutil/trace.go:171","msg":"trace[973671448] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"370.100724ms","start":"2026-09-18T22:55:11.707066Z","end":"2026-09-18T22:55:12.077167Z","steps":["trace[973671448] 'process raft request' (duration: 369.170813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.077299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.707045Z","time spent":"370.171785ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:55:12.077374Z","caller":"traceutil/trace.go:171","msg":"trace[1220110093] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"370.309668ms","start":"2026-09-18T22:55:11.707042Z","end":"2026-09-18T22:55:12.077352Z","steps":["trace[1220110093] 'process raft request' (duration: 369.262175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.077483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.707003Z","time spent":"370.42312ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:55:12.077503Z","caller":"traceutil/trace.go:171","msg":"trace[619609308] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"369.525991ms","start":"2026-09-18T22:55:11.707965Z","end":"2026-09-18T22:55:12.077491Z","steps":["trace[619609308] 'process raft request' (duration: 368.414846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.077558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.70793Z","time spent":"369.601413ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T22:55:12.07812Z","caller":"traceutil/trace.go:171","msg":"trace[444695755] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"368.156469ms","start":"2026-09-18T22:55:11.709931Z","end":"2026-09-18T22:55:12.078088Z","steps":["trace[444695755] 'process raft request' (duration: 366.584454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.078257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.709902Z","time spent":"368.302263ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:55:12.079844Z","caller":"traceutil/trace.go:171","msg":"trace[801170030] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:357; }","duration":"103.413583ms","start":"2026-09-18T22:55:11.976412Z","end":"2026-09-18T22:55:12.079825Z","steps":["trace[801170030] 'read index received' (duration: 83.557656ms)","trace[801170030] 'applied index is now lower than readState.Index' (duration: 19.855027ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:55:12.079937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.48139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-09-18T22:55:12.079966Z","caller":"traceutil/trace.go:171","msg":"trace[2003354532] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:359; }","duration":"377.565742ms","start":"2026-09-18T22:55:11.702392Z","end":"2026-09-18T22:55:12.079958Z","steps":["trace[2003354532] 'agreement among raft nodes before linearized reading' (duration: 377.487711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.079989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.702363Z","time spent":"377.620004ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-09-18T22:55:12.080195Z","caller":"traceutil/trace.go:171","msg":"trace[1349230158] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"369.028279ms","start":"2026-09-18T22:55:11.711091Z","end":"2026-09-18T22:55:12.080119Z","steps":["trace[1349230158] 'process raft request' (duration: 368.583639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.080253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.711002Z","time spent":"369.218373ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:55:12.08039Z","caller":"traceutil/trace.go:171","msg":"trace[335358232] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"367.852291ms","start":"2026-09-18T22:55:11.712517Z","end":"2026-09-18T22:55:12.080369Z","steps":["trace[335358232] 'process raft request' (duration: 367.233698ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:12.080227Z","caller":"traceutil/trace.go:171","msg":"trace[1955287217] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"369.211342ms","start":"2026-09-18T22:55:11.710981Z","end":"2026-09-18T22:55:12.080192Z","steps":["trace[1955287217] 'process raft request' (duration: 365.99329ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:12.080451Z","caller":"traceutil/trace.go:171","msg":"trace[1205792974] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"366.125433ms","start":"2026-09-18T22:55:11.714315Z","end":"2026-09-18T22:55:12.080441Z","steps":["trace[1205792974] 'process raft request' (duration: 365.464438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.080495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.712482Z","time spent":"367.961174ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T22:55:12.080616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.710965Z","time spent":"369.51876ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T22:55:12.080629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.874722ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:55:12.080739Z","caller":"traceutil/trace.go:171","msg":"trace[1631014248] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:359; }","duration":"330.986784ms","start":"2026-09-18T22:55:11.749741Z","end":"2026-09-18T22:55:12.080728Z","steps":["trace[1631014248] 'agreement among raft nodes before linearized reading' (duration: 330.858971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.081981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.714277Z","time spent":"366.207165ms","remote":"127.0.0.1:51334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T22:55:12.763177Z","caller":"traceutil/trace.go:171","msg":"trace[1012025237] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"184.030663ms","start":"2026-09-18T22:55:12.579088Z","end":"2026-09-18T22:55:12.763119Z","steps":["trace[1012025237] 'process raft request' (duration: 183.869099ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:12.920263Z","caller":"traceutil/trace.go:171","msg":"trace[16311395] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"145.399803ms","start":"2026-09-18T22:55:12.774837Z","end":"2026-09-18T22:55:12.920237Z","steps":["trace[16311395] 'process raft request' (duration: 145.116707ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:00.561986Z","caller":"traceutil/trace.go:171","msg":"trace[1036252146] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"115.247356ms","start":"2026-09-18T22:56:00.446728Z","end":"2026-09-18T22:56:00.561975Z","steps":["trace[1036252146] 'process raft request' (duration: 114.9315ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:00.561985Z","caller":"traceutil/trace.go:171","msg":"trace[1783536837] transaction","detail":"{read_only:false; number_of_response:1; response_revision:631; }","duration":"104.919765ms","start":"2026-09-18T22:56:00.457045Z","end":"2026-09-18T22:56:00.561965Z","steps":["trace[1783536837] 'process raft request' (duration: 104.787403ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:06.025947Z","caller":"traceutil/trace.go:171","msg":"trace[95910124] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"102.108541ms","start":"2026-09-18T22:56:05.923806Z","end":"2026-09-18T22:56:06.025915Z","steps":["trace[95910124] 'process raft request' (duration: 101.471522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:06.538427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.334245ms","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-09-18T22:56:06.538547Z","caller":"traceutil/trace.go:171","msg":"trace[1638301608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"197.505367ms","start":"2026-09-18T22:56:06.341013Z","end":"2026-09-18T22:56:06.538519Z","steps":["trace[1638301608] 'range keys from in-memory index tree' (duration: 197.057171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:06.539005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.605721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-18T22:56:06.539015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.203959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-18T22:56:06.539135Z","caller":"traceutil/trace.go:171","msg":"trace[1213606469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"106.685983ms","start":"2026-09-18T22:56:06.432349Z","end":"2026-09-18T22:56:06.539035Z","steps":["trace[1213606469] 'range keys from in-memory index tree' (duration: 106.48215ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:06.539257Z","caller":"traceutil/trace.go:171","msg":"trace[1402259342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"129.487983ms","start":"2026-09-18T22:56:06.409678Z","end":"2026-09-18T22:56:06.539166Z","steps":["trace[1402259342] 'range keys from in-memory index tree' (duration: 128.940075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:07.103603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2408758082394287546,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T22:56:07.489896Z","caller":"traceutil/trace.go:171","msg":"trace[1141706974] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"961.801235ms","start":"2026-09-18T22:56:06.528064Z","end":"2026-09-18T22:56:07.489865Z","steps":["trace[1141706974] 'process raft request' (duration: 960.999082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:07.490068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:06.528014Z","time spent":"961.971478ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:56:07.599847Z","caller":"traceutil/trace.go:171","msg":"trace[1127084441] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.050258965s","start":"2026-09-18T22:56:06.549571Z","end":"2026-09-18T22:56:07.59983Z","steps":["trace[1127084441] 'process raft request' (duration: 1.050213145s)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.599921Z","caller":"traceutil/trace.go:171","msg":"trace[1843761732] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:710; }","duration":"996.99878ms","start":"2026-09-18T22:56:06.602914Z","end":"2026-09-18T22:56:07.599913Z","steps":["trace[1843761732] 'read index received' (duration: 885.975322ms)","trace[1843761732] 'applied index is now lower than readState.Index' (duration: 111.022258ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:56:07.599956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:06.549547Z","time spent":"1.050351926s","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:56:07.599986Z","caller":"traceutil/trace.go:171","msg":"trace[211451074] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.050268426s","start":"2026-09-18T22:56:06.549711Z","end":"2026-09-18T22:56:07.599979Z","steps":["trace[211451074] 'process raft request' (duration: 1.050097073s)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.599852Z","caller":"traceutil/trace.go:171","msg":"trace[1850130729] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.054724932s","start":"2026-09-18T22:56:06.545115Z","end":"2026-09-18T22:56:07.59984Z","steps":["trace[1850130729] 'process raft request' (duration: 1.054538379s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:07.60002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:06.549706Z","time spent":"1.050292956s","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T22:56:07.60005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:06.545081Z","time spent":"1.054949195s","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T22:56:07.600154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.250584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T22:56:07.600178Z","caller":"traceutil/trace.go:171","msg":"trace[473446287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"997.321284ms","start":"2026-09-18T22:56:06.602849Z","end":"2026-09-18T22:56:07.60017Z","steps":["trace[473446287] 'agreement among raft nodes before linearized reading' (duration: 997.242723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:07.600202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:06.602825Z","time spent":"997.372036ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T22:56:07.61192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005310713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-18T22:56:07.61193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.125073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:56:07.611975Z","caller":"traceutil/trace.go:171","msg":"trace[1692055898] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:688; }","duration":"1.005397844s","start":"2026-09-18T22:56:06.606565Z","end":"2026-09-18T22:56:07.611963Z","steps":["trace[1692055898] 'agreement among raft nodes before linearized reading' (duration: 1.005268032s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:07.612008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:06.606551Z","time spent":"1.005448345s","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-09-18T22:56:07.612004Z","caller":"traceutil/trace.go:171","msg":"trace[421057671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"679.232294ms","start":"2026-09-18T22:56:06.932753Z","end":"2026-09-18T22:56:07.611985Z","steps":["trace[421057671] 'agreement among raft nodes before linearized reading' (duration: 679.133453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:07.612045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:06.932711Z","time spent":"679.323796ms","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T22:56:07.611922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.833408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T22:56:07.612266Z","caller":"traceutil/trace.go:171","msg":"trace[1734389555] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:688; }","duration":"463.240393ms","start":"2026-09-18T22:56:07.149009Z","end":"2026-09-18T22:56:07.61225Z","steps":["trace[1734389555] 'agreement among raft nodes before linearized reading' (duration: 462.844157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:07.612325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:07.148987Z","time spent":"463.320825ms","remote":"127.0.0.1:50924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":20,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-09-18T22:56:07.612223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.978644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g9kng\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T22:56:07.612385Z","caller":"traceutil/trace.go:171","msg":"trace[383948957] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g9kng; range_end:; response_count:1; response_revision:688; }","duration":"848.173436ms","start":"2026-09-18T22:56:06.764195Z","end":"2026-09-18T22:56:07.612368Z","steps":["trace[383948957] 'agreement among raft nodes before linearized reading' (duration: 847.933922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:07.612442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:06.764176Z","time spent":"848.251828ms","remote":"127.0.0.1:51026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-g9kng\" "} {"level":"info","ts":"2026-09-18T22:56:08.236878Z","caller":"traceutil/trace.go:171","msg":"trace[2078159032] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"302.64724ms","start":"2026-09-18T22:56:07.934141Z","end":"2026-09-18T22:56:08.236788Z","steps":["trace[2078159032] 'read index received' (duration: 298.933006ms)","trace[2078159032] 'applied index is now lower than readState.Index' (duration: 3.711744ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:56:08.237173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.002955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:56:08.237281Z","caller":"traceutil/trace.go:171","msg":"trace[658023936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"303.078716ms","start":"2026-09-18T22:56:07.934131Z","end":"2026-09-18T22:56:08.23721Z","steps":["trace[658023936] 'agreement among raft nodes before linearized reading' (duration: 302.858943ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:08.237336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:07.934046Z","time spent":"303.27621ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T22:56:08.23793Z","caller":"traceutil/trace.go:171","msg":"trace[761955195] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"604.353857ms","start":"2026-09-18T22:56:07.633529Z","end":"2026-09-18T22:56:08.237883Z","steps":["trace[761955195] 'process raft request' (duration: 600.365648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:08.23808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:07.63349Z","time spent":"604.488469ms","remote":"127.0.0.1:51026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:56:08.249016Z","caller":"traceutil/trace.go:171","msg":"trace[652895390] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"147.140111ms","start":"2026-09-18T22:56:08.101838Z","end":"2026-09-18T22:56:08.248978Z","steps":["trace[652895390] 'process raft request' (duration: 145.813521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:25.512835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.80741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T22:56:25.512953Z","caller":"traceutil/trace.go:171","msg":"trace[642799303] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"112.91234ms","start":"2026-09-18T22:56:25.400024Z","end":"2026-09-18T22:56:25.512936Z","steps":["trace[642799303] 'read index received' (duration: 40.7µs)","trace[642799303] 'applied index is now lower than readState.Index' (duration: 112.8702ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:56:25.513066Z","caller":"traceutil/trace.go:171","msg":"trace[867846949] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"379.663152ms","start":"2026-09-18T22:56:25.133376Z","end":"2026-09-18T22:56:25.513039Z","steps":["trace[867846949] 'process raft request' (duration: 211.573028ms)","trace[867846949] 'compare' (duration: 167.623508ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:56:25.513209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:25.133347Z","time spent":"379.804644ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T22:56:25.51309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.072963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.104\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-18T22:56:25.513343Z","caller":"traceutil/trace.go:171","msg":"trace[2096996442] range","detail":"{range_begin:/registry/masterleases/162.253.55.104; range_end:; response_count:1; response_revision:855; }","duration":"113.329455ms","start":"2026-09-18T22:56:25.399969Z","end":"2026-09-18T22:56:25.513298Z","steps":["trace[2096996442] 'agreement among raft nodes before linearized reading' (duration: 113.023331ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:58:20.075916Z","caller":"traceutil/trace.go:171","msg":"trace[1276551054] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"104.841004ms","start":"2026-09-18T22:58:19.971052Z","end":"2026-09-18T22:58:20.075893Z","steps":["trace[1276551054] 'process raft request' (duration: 104.642142ms)"],"step_count":1}