{"level":"warn","ts":"2026-01-24T02:38:56.626042Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-24T02:38:56.626186Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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://199.204.45.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-01-24T02:38:56.626298Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-24T02:38:56.626308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-01-24T02:38:56.626331Z","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-01-24T02:38:56.630411Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-01-24T02:38:56.630518Z","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":2,"max-cpu-available":2,"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://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-01-24T02:38:56.636997Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.700977ms"} {"level":"info","ts":"2026-01-24T02:38:56.658387Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-01-24T02:38:56.658471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-01-24T02:38:56.658496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-01-24T02:38:56.658505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-24T02:38:56.658514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-01-24T02:38:56.658552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-01-24T02:38:56.665537Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-24T02:38:56.6677Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-24T02:38:56.669668Z","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-01-24T02:38:56.675368Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-24T02:38:56.676367Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-24T02:38:56.676617Z","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-01-24T02:38:56.676827Z","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-01-24T02:38:56.676873Z","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-01-24T02:38:56.677218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-01-24T02:38:56.678481Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-01-24T02:38:56.707499Z","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-01-24T02:38:56.707885Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-01-24T02:38:56.709571Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-01-24T02:38:56.709689Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-24T02:38:56.709792Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-24T02:38:57.559928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-01-24T02:38:57.559982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-01-24T02:38:57.560015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-01-24T02:38:57.560027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-01-24T02:38:57.560033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-01-24T02:38:57.560041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-01-24T02:38:57.560049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-01-24T02:38:57.562279Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-24T02:38:57.56259Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-24T02:38:57.562254Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-01-24T02:38:57.562664Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-24T02:38:57.569449Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-01-24T02:38:57.572618Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-24T02:38:57.573972Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-01-24T02:38:57.574066Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-24T02:38:57.574088Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-24T02:38:57.59418Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-24T02:38:57.594206Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-01-24T02:39:08.306189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.548999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-24T02:39:08.306287Z","caller":"traceutil/trace.go:171","msg":"trace[683159081] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:349; }","duration":"380.674842ms","start":"2026-01-24T02:39:07.925591Z","end":"2026-01-24T02:39:08.306266Z","steps":["trace[683159081] 'range keys from in-memory index tree' (duration: 380.448646ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.307334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:07.925535Z","time spent":"381.646449ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-01-24T02:39:08.307809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.465516ms","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-01-24T02:39:08.307842Z","caller":"traceutil/trace.go:171","msg":"trace[2123124539] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:349; }","duration":"332.500748ms","start":"2026-01-24T02:39:07.975334Z","end":"2026-01-24T02:39:08.307835Z","steps":["trace[2123124539] 'range keys from in-memory index tree' (duration: 332.197449ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.308061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:07.975306Z","time spent":"332.669041ms","remote":"127.0.0.1:37592","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-01-24T02:39:08.311053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.005871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-01-24T02:39:08.311098Z","caller":"traceutil/trace.go:171","msg":"trace[41632434] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:349; }","duration":"285.098403ms","start":"2026-01-24T02:39:08.02599Z","end":"2026-01-24T02:39:08.311089Z","steps":["trace[41632434] 'range keys from in-memory index tree' (duration: 284.860467ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.311196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.1152ms","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-01-24T02:39:08.311226Z","caller":"traceutil/trace.go:171","msg":"trace[2074136723] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:349; }","duration":"235.14304ms","start":"2026-01-24T02:39:08.076072Z","end":"2026-01-24T02:39:08.311215Z","steps":["trace[2074136723] 'range keys from in-memory index tree' (duration: 235.036627ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.311471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.075271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-24T02:39:08.311504Z","caller":"traceutil/trace.go:171","msg":"trace[325261332] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:349; }","duration":"186.141643ms","start":"2026-01-24T02:39:08.12535Z","end":"2026-01-24T02:39:08.311492Z","steps":["trace[325261332] 'range keys from in-memory index tree' (duration: 186.00889ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.312244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.042946ms","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-01-24T02:39:08.312278Z","caller":"traceutil/trace.go:171","msg":"trace[309420355] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:349; }","duration":"136.141909ms","start":"2026-01-24T02:39:08.176127Z","end":"2026-01-24T02:39:08.312269Z","steps":["trace[309420355] 'range keys from in-memory index tree' (duration: 135.973604ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.312605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.101027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-01-24T02:39:08.312742Z","caller":"traceutil/trace.go:171","msg":"trace[720707882] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:349; }","duration":"112.177139ms","start":"2026-01-24T02:39:08.200496Z","end":"2026-01-24T02:39:08.312673Z","steps":["trace[720707882] 'range keys from in-memory index tree' (duration: 112.038696ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.313054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.382685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-01-24T02:39:08.313083Z","caller":"traceutil/trace.go:171","msg":"trace[607809037] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:349; }","duration":"112.425886ms","start":"2026-01-24T02:39:08.20065Z","end":"2026-01-24T02:39:08.313076Z","steps":["trace[607809037] 'range keys from in-memory index tree' (duration: 112.338954ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.313447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.20396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4597"} {"level":"info","ts":"2026-01-24T02:39:08.313474Z","caller":"traceutil/trace.go:171","msg":"trace[2136907130] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:349; }","duration":"112.245531ms","start":"2026-01-24T02:39:08.201222Z","end":"2026-01-24T02:39:08.313468Z","steps":["trace[2136907130] 'range keys from in-memory index tree' (duration: 112.162749ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.3139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.704544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-01-24T02:39:08.313931Z","caller":"traceutil/trace.go:171","msg":"trace[1631306867] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:349; }","duration":"112.749675ms","start":"2026-01-24T02:39:08.201173Z","end":"2026-01-24T02:39:08.313923Z","steps":["trace[1631306867] 'range keys from in-memory index tree' (duration: 112.600361ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:08.315167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.356251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-01-24T02:39:08.315265Z","caller":"traceutil/trace.go:171","msg":"trace[338699231] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:349; }","duration":"113.534866ms","start":"2026-01-24T02:39:08.201701Z","end":"2026-01-24T02:39:08.315236Z","steps":["trace[338699231] 'range keys from in-memory index tree' (duration: 113.108635ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:09.565071Z","caller":"traceutil/trace.go:171","msg":"trace[2143471405] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"113.765542ms","start":"2026-01-24T02:39:09.451282Z","end":"2026-01-24T02:39:09.565048Z","steps":["trace[2143471405] 'process raft request' (duration: 113.649499ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:13.989942Z","caller":"traceutil/trace.go:171","msg":"trace[384408911] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"145.267305ms","start":"2026-01-24T02:39:13.84465Z","end":"2026-01-24T02:39:13.989917Z","steps":["trace[384408911] 'process raft request' (duration: 145.105331ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:14.433921Z","caller":"traceutil/trace.go:171","msg":"trace[348170278] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"167.055675ms","start":"2026-01-24T02:39:14.266849Z","end":"2026-01-24T02:39:14.433905Z","steps":["trace[348170278] 'process raft request' (duration: 166.919222ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:19.66058Z","caller":"traceutil/trace.go:171","msg":"trace[652950142] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"157.069865ms","start":"2026-01-24T02:39:19.503494Z","end":"2026-01-24T02:39:19.660564Z","steps":["trace[652950142] 'read index received' (duration: 157.035414ms)","trace[652950142] 'applied index is now lower than readState.Index' (duration: 33.931µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:39:19.660709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.198859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-24T02:39:19.660726Z","caller":"traceutil/trace.go:171","msg":"trace[1028464076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:456; }","duration":"157.229039ms","start":"2026-01-24T02:39:19.503492Z","end":"2026-01-24T02:39:19.660721Z","steps":["trace[1028464076] 'agreement among raft nodes before linearized reading' (duration: 157.182218ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:19.660743Z","caller":"traceutil/trace.go:171","msg":"trace[1342044246] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"158.960897ms","start":"2026-01-24T02:39:19.501773Z","end":"2026-01-24T02:39:19.660734Z","steps":["trace[1342044246] 'process raft request' (duration: 158.69558ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:19.78278Z","caller":"traceutil/trace.go:171","msg":"trace[19916531] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"120.056113ms","start":"2026-01-24T02:39:19.662707Z","end":"2026-01-24T02:39:19.782763Z","steps":["trace[19916531] 'read index received' (duration: 119.250271ms)","trace[19916531] 'applied index is now lower than readState.Index' (duration: 805.242µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:39:19.782882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.157265ms","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-01-24T02:39:19.782904Z","caller":"traceutil/trace.go:171","msg":"trace[1682214126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"120.208766ms","start":"2026-01-24T02:39:19.662687Z","end":"2026-01-24T02:39:19.782896Z","steps":["trace[1682214126] 'agreement among raft nodes before linearized reading' (duration: 120.140204ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:19.784002Z","caller":"traceutil/trace.go:171","msg":"trace[1769911772] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"277.594421ms","start":"2026-01-24T02:39:19.506397Z","end":"2026-01-24T02:39:19.783992Z","steps":["trace[1769911772] 'process raft request' (duration: 275.3452ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:19.789262Z","caller":"traceutil/trace.go:171","msg":"trace[770446551] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"124.74205ms","start":"2026-01-24T02:39:19.664496Z","end":"2026-01-24T02:39:19.789238Z","steps":["trace[770446551] 'process raft request' (duration: 124.654248ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:23.799289Z","caller":"traceutil/trace.go:171","msg":"trace[2093334787] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"125.765938ms","start":"2026-01-24T02:39:23.673503Z","end":"2026-01-24T02:39:23.799269Z","steps":["trace[2093334787] 'process raft request' (duration: 125.579173ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:30.061885Z","caller":"traceutil/trace.go:171","msg":"trace[445405152] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"228.274965ms","start":"2026-01-24T02:39:29.833559Z","end":"2026-01-24T02:39:30.061834Z","steps":["trace[445405152] 'read index received' (duration: 227.944276ms)","trace[445405152] 'applied index is now lower than readState.Index' (duration: 329.269µs)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:39:30.062411Z","caller":"traceutil/trace.go:171","msg":"trace[709814164] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"229.326074ms","start":"2026-01-24T02:39:29.83307Z","end":"2026-01-24T02:39:30.062396Z","steps":["trace[709814164] 'process raft request' (duration: 228.556223ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:30.062775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.163829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-24T02:39:30.062813Z","caller":"traceutil/trace.go:171","msg":"trace[2071189502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"229.255102ms","start":"2026-01-24T02:39:29.833547Z","end":"2026-01-24T02:39:30.062802Z","steps":["trace[2071189502] 'agreement among raft nodes before linearized reading' (duration: 229.121688ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:30.06307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.976403ms","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-01-24T02:39:30.064445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.723308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-01-24T02:39:30.064533Z","caller":"traceutil/trace.go:171","msg":"trace[1079139200] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:514; }","duration":"128.859621ms","start":"2026-01-24T02:39:29.935653Z","end":"2026-01-24T02:39:30.064513Z","steps":["trace[1079139200] 'agreement among raft nodes before linearized reading' (duration: 128.688427ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:30.064943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.595302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-24T02:39:30.064974Z","caller":"traceutil/trace.go:171","msg":"trace[1840980571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"191.630973ms","start":"2026-01-24T02:39:29.873334Z","end":"2026-01-24T02:39:30.064965Z","steps":["trace[1840980571] 'agreement among raft nodes before linearized reading' (duration: 191.567581ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:30.06622Z","caller":"traceutil/trace.go:171","msg":"trace[1067580329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"195.029794ms","start":"2026-01-24T02:39:29.868062Z","end":"2026-01-24T02:39:30.063092Z","steps":["trace[1067580329] 'agreement among raft nodes before linearized reading' (duration: 194.922591ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:30.225703Z","caller":"traceutil/trace.go:171","msg":"trace[42123210] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"150.250021ms","start":"2026-01-24T02:39:30.075423Z","end":"2026-01-24T02:39:30.225673Z","steps":["trace[42123210] 'process raft request' (duration: 149.393648ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:30.231257Z","caller":"traceutil/trace.go:171","msg":"trace[2017014483] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"153.249292ms","start":"2026-01-24T02:39:30.077997Z","end":"2026-01-24T02:39:30.231246Z","steps":["trace[2017014483] 'process raft request' (duration: 153.216651ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:30.231554Z","caller":"traceutil/trace.go:171","msg":"trace[636740444] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"154.829825ms","start":"2026-01-24T02:39:30.076714Z","end":"2026-01-24T02:39:30.231544Z","steps":["trace[636740444] 'process raft request' (duration: 154.469195ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:30.23127Z","caller":"traceutil/trace.go:171","msg":"trace[2113303813] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"155.679218ms","start":"2026-01-24T02:39:30.075557Z","end":"2026-01-24T02:39:30.231236Z","steps":["trace[2113303813] 'process raft request' (duration: 155.561105ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:31.153589Z","caller":"traceutil/trace.go:171","msg":"trace[1232735813] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"175.641391ms","start":"2026-01-24T02:39:30.977925Z","end":"2026-01-24T02:39:31.153566Z","steps":["trace[1232735813] 'process raft request' (duration: 173.702249ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:33.10853Z","caller":"traceutil/trace.go:171","msg":"trace[654620378] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"224.560824ms","start":"2026-01-24T02:39:32.883922Z","end":"2026-01-24T02:39:33.108483Z","steps":["trace[654620378] 'process raft request' (duration: 223.636299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:38.58317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208183845365257870,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-24T02:39:38.608651Z","caller":"traceutil/trace.go:171","msg":"trace[1168675242] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"525.916929ms","start":"2026-01-24T02:39:38.082711Z","end":"2026-01-24T02:39:38.608628Z","steps":["trace[1168675242] 'read index received' (duration: 525.617721ms)","trace[1168675242] 'applied index is now lower than readState.Index' (duration: 298.248µs)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:39:38.608798Z","caller":"traceutil/trace.go:171","msg":"trace[564282092] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"866.507686ms","start":"2026-01-24T02:39:37.742281Z","end":"2026-01-24T02:39:38.608788Z","steps":["trace[564282092] 'process raft request' (duration: 866.160167ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:38.609134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.261763ms","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-01-24T02:39:38.609215Z","caller":"traceutil/trace.go:171","msg":"trace[386264627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"500.372196ms","start":"2026-01-24T02:39:38.108819Z","end":"2026-01-24T02:39:38.609192Z","steps":["trace[386264627] 'agreement among raft nodes before linearized reading' (duration: 500.253383ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:38.609258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:38.108805Z","time spent":"500.443539ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-24T02:39:38.609446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.739701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:39:38.609494Z","caller":"traceutil/trace.go:171","msg":"trace[1317357947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"526.785872ms","start":"2026-01-24T02:39:38.082698Z","end":"2026-01-24T02:39:38.609484Z","steps":["trace[1317357947] 'agreement among raft nodes before linearized reading' (duration: 526.7226ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:38.60952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:38.08264Z","time spent":"526.873854ms","remote":"127.0.0.1:37352","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-01-24T02:39:38.609582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:37.742265Z","time spent":"866.561777ms","remote":"127.0.0.1:37570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:39:38.60966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.051017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-24T02:39:38.609689Z","caller":"traceutil/trace.go:171","msg":"trace[891922374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"325.130858ms","start":"2026-01-24T02:39:38.28455Z","end":"2026-01-24T02:39:38.609681Z","steps":["trace[891922374] 'agreement among raft nodes before linearized reading' (duration: 325.082217ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:38.609747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:38.284534Z","time spent":"325.205641ms","remote":"127.0.0.1:37660","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":"warn","ts":"2026-01-24T02:39:38.609874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.959878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-24T02:39:38.609901Z","caller":"traceutil/trace.go:171","msg":"trace[2099818158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"344.03196ms","start":"2026-01-24T02:39:38.265861Z","end":"2026-01-24T02:39:38.609893Z","steps":["trace[2099818158] 'agreement among raft nodes before linearized reading' (duration: 343.986709ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:38.609925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:38.26585Z","time spent":"344.070052ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-24T02:39:38.610046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.570931ms","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-01-24T02:39:38.610073Z","caller":"traceutil/trace.go:171","msg":"trace[1638055602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"349.634183ms","start":"2026-01-24T02:39:38.260431Z","end":"2026-01-24T02:39:38.610066Z","steps":["trace[1638055602] 'agreement among raft nodes before linearized reading' (duration: 349.584792ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:38.610099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:38.260386Z","time spent":"349.705205ms","remote":"127.0.0.1:37660","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-01-24T02:39:39.848539Z","caller":"traceutil/trace.go:171","msg":"trace[205326425] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"103.463783ms","start":"2026-01-24T02:39:39.745028Z","end":"2026-01-24T02:39:39.848492Z","steps":["trace[205326425] 'process raft request' (duration: 103.35488ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:41.197094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.389569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:39:41.197182Z","caller":"traceutil/trace.go:171","msg":"trace[1627635398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:562; }","duration":"238.497362ms","start":"2026-01-24T02:39:40.958665Z","end":"2026-01-24T02:39:41.197163Z","steps":["trace[1627635398] 'range keys from in-memory index tree' (duration: 238.332688ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:43.029474Z","caller":"traceutil/trace.go:171","msg":"trace[757550478] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"117.414791ms","start":"2026-01-24T02:39:42.912013Z","end":"2026-01-24T02:39:43.029428Z","steps":["trace[757550478] 'process raft request' (duration: 117.284217ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:52.899752Z","caller":"traceutil/trace.go:171","msg":"trace[1803032795] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"101.75298ms","start":"2026-01-24T02:39:52.797982Z","end":"2026-01-24T02:39:52.899735Z","steps":["trace[1803032795] 'process raft request' (duration: 101.615666ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:39:52.900094Z","caller":"traceutil/trace.go:171","msg":"trace[438745733] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:734; }","duration":"101.517953ms","start":"2026-01-24T02:39:52.798566Z","end":"2026-01-24T02:39:52.900084Z","steps":["trace[438745733] 'read index received' (duration: 101.515083ms)","trace[438745733] 'applied index is now lower than readState.Index' (duration: 2.37µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:39:52.900197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.616196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-24T02:39:52.900214Z","caller":"traceutil/trace.go:171","msg":"trace[1942237555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"101.656048ms","start":"2026-01-24T02:39:52.798553Z","end":"2026-01-24T02:39:52.900209Z","steps":["trace[1942237555] 'agreement among raft nodes before linearized reading' (duration: 101.568435ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:53.401013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208183845365258198,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-24T02:39:53.922219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208183845365258198,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-24T02:39:54.418705Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.519109073s","expected-duration":"1s"} {"level":"info","ts":"2026-01-24T02:39:54.41919Z","caller":"traceutil/trace.go:171","msg":"trace[1734781154] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"1.568320843s","start":"2026-01-24T02:39:52.850847Z","end":"2026-01-24T02:39:54.419168Z","steps":["trace[1734781154] 'process raft request' (duration: 1.568120427s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.419293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:52.850831Z","time spent":"1.568401735s","remote":"127.0.0.1:37434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-24T02:39:54.421031Z","caller":"traceutil/trace.go:171","msg":"trace[1603371919] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:735; }","duration":"1.520909592s","start":"2026-01-24T02:39:52.900112Z","end":"2026-01-24T02:39:54.421021Z","steps":["trace[1603371919] 'read index received' (duration: 1.519612967s)","trace[1603371919] 'applied index is now lower than readState.Index' (duration: 1.296205ms)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:39:54.421175Z","caller":"traceutil/trace.go:171","msg":"trace[1525571929] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"1.518876446s","start":"2026-01-24T02:39:52.902291Z","end":"2026-01-24T02:39:54.421167Z","steps":["trace[1525571929] 'process raft request' (duration: 1.51863528s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.421223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:52.902278Z","time spent":"1.518913558s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:39:54.421305Z","caller":"traceutil/trace.go:171","msg":"trace[2178571] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"1.041467001s","start":"2026-01-24T02:39:53.379832Z","end":"2026-01-24T02:39:54.421299Z","steps":["trace[2178571] 'process raft request' (duration: 1.041164883s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.421336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:53.379813Z","time spent":"1.041504811s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:39:54.421464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570411349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-85bm5\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-01-24T02:39:54.421483Z","caller":"traceutil/trace.go:171","msg":"trace[2022622263] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-85bm5; range_end:; response_count:1; response_revision:712; }","duration":"1.570478232s","start":"2026-01-24T02:39:52.850999Z","end":"2026-01-24T02:39:54.421477Z","steps":["trace[2022622263] 'agreement among raft nodes before linearized reading' (duration: 1.5704038s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.421503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:52.850988Z","time spent":"1.570511022s","remote":"127.0.0.1:37570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4848,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-85bm5\" "} {"level":"warn","ts":"2026-01-24T02:39:54.421618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577299907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-24T02:39:54.421647Z","caller":"traceutil/trace.go:171","msg":"trace[1549236013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"1.577333578s","start":"2026-01-24T02:39:52.844296Z","end":"2026-01-24T02:39:54.42163Z","steps":["trace[1549236013] 'agreement among raft nodes before linearized reading' (duration: 1.577287536s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.421663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:52.844283Z","time spent":"1.57737607s","remote":"127.0.0.1:37660","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":"warn","ts":"2026-01-24T02:39:54.421753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600012895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-24T02:39:54.421768Z","caller":"traceutil/trace.go:171","msg":"trace[200603861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"1.600053966s","start":"2026-01-24T02:39:52.82171Z","end":"2026-01-24T02:39:54.421764Z","steps":["trace[200603861] 'agreement among raft nodes before linearized reading' (duration: 1.600011695s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.421784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:52.821682Z","time spent":"1.600097638s","remote":"127.0.0.1:37660","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":"warn","ts":"2026-01-24T02:39:54.421881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.614785ms","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-01-24T02:39:54.421897Z","caller":"traceutil/trace.go:171","msg":"trace[1472190126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:712; }","duration":"904.651196ms","start":"2026-01-24T02:39:53.517241Z","end":"2026-01-24T02:39:54.421892Z","steps":["trace[1472190126] 'agreement among raft nodes before linearized reading' (duration: 904.605185ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.421911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:53.517228Z","time spent":"904.680237ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-24T02:39:54.422081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.541363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:39:54.422097Z","caller":"traceutil/trace.go:171","msg":"trace[1311520711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"339.587214ms","start":"2026-01-24T02:39:54.082505Z","end":"2026-01-24T02:39:54.422093Z","steps":["trace[1311520711] 'agreement among raft nodes before linearized reading' (duration: 339.562164ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.422114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:54.082484Z","time spent":"339.625855ms","remote":"127.0.0.1:37352","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-01-24T02:39:54.741144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.240009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-24T02:39:54.74129Z","caller":"traceutil/trace.go:171","msg":"trace[727536791] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"305.104165ms","start":"2026-01-24T02:39:54.43616Z","end":"2026-01-24T02:39:54.741264Z","steps":["trace[727536791] 'process raft request' (duration: 118.440974ms)","trace[727536791] 'compare' (duration: 186.097466ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:39:54.741359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:54.436139Z","time spent":"305.191737ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:39:54.78579Z","caller":"traceutil/trace.go:171","msg":"trace[1104310722] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"320.286168ms","start":"2026-01-24T02:39:54.465479Z","end":"2026-01-24T02:39:54.785765Z","steps":["trace[1104310722] 'process raft request' (duration: 320.251947ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.78598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:54.46545Z","time spent":"320.417311ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:39:54.786296Z","caller":"traceutil/trace.go:171","msg":"trace[799830387] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"322.899569ms","start":"2026-01-24T02:39:54.46339Z","end":"2026-01-24T02:39:54.78629Z","steps":["trace[799830387] 'process raft request' (duration: 322.21424ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:54.786329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:54.463363Z","time spent":"322.94657ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:39:55.37799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.178039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-24T02:39:55.378097Z","caller":"traceutil/trace.go:171","msg":"trace[1806481593] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"358.452907ms","start":"2026-01-24T02:39:55.019624Z","end":"2026-01-24T02:39:55.378077Z","steps":["trace[1806481593] 'process raft request' (duration: 158.061922ms)","trace[1806481593] 'compare' (duration: 199.987004ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:39:55.378145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:55.019599Z","time spent":"358.528539ms","remote":"127.0.0.1:37434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-24T02:39:55.808806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.831605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:39:55.808901Z","caller":"traceutil/trace.go:171","msg":"trace[1070055932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"324.006461ms","start":"2026-01-24T02:39:55.484873Z","end":"2026-01-24T02:39:55.80888Z","steps":["trace[1070055932] 'range keys from in-memory index tree' (duration: 323.742973ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:39:55.808943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:39:55.484848Z","time spent":"324.086122ms","remote":"127.0.0.1:37352","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-01-24T02:39:56.2192Z","caller":"traceutil/trace.go:171","msg":"trace[37200703] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"107.713981ms","start":"2026-01-24T02:39:56.111458Z","end":"2026-01-24T02:39:56.219172Z","steps":["trace[37200703] 'read index received' (duration: 103.584248ms)","trace[37200703] 'applied index is now lower than readState.Index' (duration: 4.128473ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:39:56.219339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.857085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:39:56.219413Z","caller":"traceutil/trace.go:171","msg":"trace[2060697648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"107.961268ms","start":"2026-01-24T02:39:56.111425Z","end":"2026-01-24T02:39:56.219386Z","steps":["trace[2060697648] 'agreement among raft nodes before linearized reading' (duration: 107.832825ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:03.81814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.009563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:03.818209Z","caller":"traceutil/trace.go:171","msg":"trace[2139825970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"737.114446ms","start":"2026-01-24T02:40:03.081076Z","end":"2026-01-24T02:40:03.81819Z","steps":["trace[2139825970] 'range keys from in-memory index tree' (duration: 736.936021ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:03.818239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:03.08106Z","time spent":"737.172217ms","remote":"127.0.0.1:37352","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-01-24T02:40:03.818328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.602365ms","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-01-24T02:40:03.818383Z","caller":"traceutil/trace.go:171","msg":"trace[1024367262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"724.694258ms","start":"2026-01-24T02:40:03.093674Z","end":"2026-01-24T02:40:03.818368Z","steps":["trace[1024367262] 'range keys from in-memory index tree' (duration: 724.477202ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:03.818411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:03.093646Z","time spent":"724.75902ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-24T02:40:03.818873Z","caller":"traceutil/trace.go:171","msg":"trace[808401614] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"410.858225ms","start":"2026-01-24T02:40:03.408006Z","end":"2026-01-24T02:40:03.818865Z","steps":["trace[808401614] 'process raft request' (duration: 410.766762ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:03.818955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:03.407984Z","time spent":"410.909616ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:40:04.32407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.045347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:04.32414Z","caller":"traceutil/trace.go:171","msg":"trace[240264221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"225.170149ms","start":"2026-01-24T02:40:04.098954Z","end":"2026-01-24T02:40:04.324124Z","steps":["trace[240264221] 'range keys from in-memory index tree' (duration: 224.962174ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:16.743071Z","caller":"traceutil/trace.go:171","msg":"trace[1520666703] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"167.263884ms","start":"2026-01-24T02:40:16.575787Z","end":"2026-01-24T02:40:16.743051Z","steps":["trace[1520666703] 'read index received' (duration: 167.061088ms)","trace[1520666703] 'applied index is now lower than readState.Index' (duration: 202.096µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:16.743176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.361756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:16.7432Z","caller":"traceutil/trace.go:171","msg":"trace[888692912] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:780; }","duration":"167.412128ms","start":"2026-01-24T02:40:16.575782Z","end":"2026-01-24T02:40:16.743194Z","steps":["trace[888692912] 'agreement among raft nodes before linearized reading' (duration: 167.338586ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:16.743488Z","caller":"traceutil/trace.go:171","msg":"trace[1240826228] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"200.311822ms","start":"2026-01-24T02:40:16.543168Z","end":"2026-01-24T02:40:16.74348Z","steps":["trace[1240826228] 'process raft request' (duration: 199.734507ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:20.363288Z","caller":"traceutil/trace.go:171","msg":"trace[1315428378] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"322.010186ms","start":"2026-01-24T02:40:20.041263Z","end":"2026-01-24T02:40:20.363273Z","steps":["trace[1315428378] 'read index received' (duration: 321.875312ms)","trace[1315428378] 'applied index is now lower than readState.Index' (duration: 134.344µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:20.36337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.088458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:20.363387Z","caller":"traceutil/trace.go:171","msg":"trace[1156164960] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:789; }","duration":"322.1493ms","start":"2026-01-24T02:40:20.041233Z","end":"2026-01-24T02:40:20.363382Z","steps":["trace[1156164960] 'agreement among raft nodes before linearized reading' (duration: 322.099559ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:20.363406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:20.04122Z","time spent":"322.182101ms","remote":"127.0.0.1:37700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-01-24T02:40:20.36354Z","caller":"traceutil/trace.go:171","msg":"trace[687730621] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"360.974857ms","start":"2026-01-24T02:40:20.002543Z","end":"2026-01-24T02:40:20.363518Z","steps":["trace[687730621] 'process raft request' (duration: 360.634877ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:20.363658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:20.002529Z","time spent":"361.06905ms","remote":"127.0.0.1:37660","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-01-24T02:40:20.919225Z","caller":"traceutil/trace.go:171","msg":"trace[2078350020] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"150.365004ms","start":"2026-01-24T02:40:20.768841Z","end":"2026-01-24T02:40:20.919206Z","steps":["trace[2078350020] 'process raft request' (duration: 106.304454ms)","trace[2078350020] 'compare' (duration: 43.918476ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:21.413799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.538227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-24T02:40:21.413859Z","caller":"traceutil/trace.go:171","msg":"trace[1643563727] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"327.554987ms","start":"2026-01-24T02:40:21.086295Z","end":"2026-01-24T02:40:21.41385Z","steps":["trace[1643563727] 'read index received' (duration: 111.840324ms)","trace[1643563727] 'applied index is now lower than readState.Index' (duration: 215.714043ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:21.413908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.618598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:21.413921Z","caller":"traceutil/trace.go:171","msg":"trace[1284328671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"327.65871ms","start":"2026-01-24T02:40:21.086257Z","end":"2026-01-24T02:40:21.413916Z","steps":["trace[1284328671] 'agreement among raft nodes before linearized reading' (duration: 327.615838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:21.413939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:21.086214Z","time spent":"327.720941ms","remote":"127.0.0.1:37352","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-01-24T02:40:21.414082Z","caller":"traceutil/trace.go:171","msg":"trace[235979687] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"426.407118ms","start":"2026-01-24T02:40:20.987669Z","end":"2026-01-24T02:40:21.414076Z","steps":["trace[235979687] 'process raft request' (duration: 210.524371ms)","trace[235979687] 'compare' (duration: 215.415464ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:21.414111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:20.98765Z","time spent":"426.444168ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:40:21.77567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.462294ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:21.775774Z","caller":"traceutil/trace.go:171","msg":"trace[895046666] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:794; }","duration":"115.578857ms","start":"2026-01-24T02:40:21.660182Z","end":"2026-01-24T02:40:21.775761Z","steps":["trace[895046666] 'range keys from in-memory index tree' (duration: 115.452034ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:21.775993Z","caller":"traceutil/trace.go:171","msg":"trace[1795569586] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"207.947361ms","start":"2026-01-24T02:40:21.568038Z","end":"2026-01-24T02:40:21.775985Z","steps":["trace[1795569586] 'process raft request' (duration: 176.022952ms)","trace[1795569586] 'compare' (duration: 31.506708ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:22.141903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.511629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-24T02:40:22.141984Z","caller":"traceutil/trace.go:171","msg":"trace[1593042549] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:795; }","duration":"254.630771ms","start":"2026-01-24T02:40:21.887335Z","end":"2026-01-24T02:40:22.141965Z","steps":["trace[1593042549] 'count revisions from in-memory index tree' (duration: 254.465007ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:25.139065Z","caller":"traceutil/trace.go:171","msg":"trace[2094953862] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"755.776983ms","start":"2026-01-24T02:40:24.383265Z","end":"2026-01-24T02:40:25.139042Z","steps":["trace[2094953862] 'process raft request' (duration: 755.617159ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:25.139186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:24.383243Z","time spent":"755.880647ms","remote":"127.0.0.1:37660","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-01-24T02:40:25.261016Z","caller":"traceutil/trace.go:171","msg":"trace[541322324] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"329.740887ms","start":"2026-01-24T02:40:24.931237Z","end":"2026-01-24T02:40:25.260978Z","steps":["trace[541322324] 'read index received' (duration: 207.665614ms)","trace[541322324] 'applied index is now lower than readState.Index' (duration: 122.074092ms)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:40:25.26147Z","caller":"traceutil/trace.go:171","msg":"trace[1549481408] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"673.777751ms","start":"2026-01-24T02:40:24.587677Z","end":"2026-01-24T02:40:25.261454Z","steps":["trace[1549481408] 'process raft request' (duration: 599.378636ms)","trace[1549481408] 'compare' (duration: 73.811189ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:25.261587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:24.587654Z","time spent":"673.853054ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:40:25.261851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.6127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-24T02:40:25.261884Z","caller":"traceutil/trace.go:171","msg":"trace[364130415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"330.690342ms","start":"2026-01-24T02:40:24.931183Z","end":"2026-01-24T02:40:25.261874Z","steps":["trace[364130415] 'agreement among raft nodes before linearized reading' (duration: 330.574619ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:25.261915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:24.931164Z","time spent":"330.743814ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-24T02:40:25.262096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.62171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-24T02:40:25.262127Z","caller":"traceutil/trace.go:171","msg":"trace[126796636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"298.674821ms","start":"2026-01-24T02:40:24.963443Z","end":"2026-01-24T02:40:25.262118Z","steps":["trace[126796636] 'agreement among raft nodes before linearized reading' (duration: 298.598208ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:25.262316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.018566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:25.262344Z","caller":"traceutil/trace.go:171","msg":"trace[822218535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"178.091937ms","start":"2026-01-24T02:40:25.084244Z","end":"2026-01-24T02:40:25.262336Z","steps":["trace[822218535] 'agreement among raft nodes before linearized reading' (duration: 178.049336ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:25.262554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.446024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-24T02:40:25.262584Z","caller":"traceutil/trace.go:171","msg":"trace[2085059777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"262.501046ms","start":"2026-01-24T02:40:25.000073Z","end":"2026-01-24T02:40:25.262574Z","steps":["trace[2085059777] 'agreement among raft nodes before linearized reading' (duration: 262.423654ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:25.483839Z","caller":"traceutil/trace.go:171","msg":"trace[605188301] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"210.737547ms","start":"2026-01-24T02:40:25.273081Z","end":"2026-01-24T02:40:25.483819Z","steps":["trace[605188301] 'process raft request' (duration: 178.247112ms)","trace[605188301] 'compare' (duration: 31.826946ms)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:40:25.484189Z","caller":"traceutil/trace.go:171","msg":"trace[1908914444] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"211.050935ms","start":"2026-01-24T02:40:25.273117Z","end":"2026-01-24T02:40:25.484168Z","steps":["trace[1908914444] 'process raft request' (duration: 210.337766ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:37.688437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.422224ms","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-01-24T02:40:37.688486Z","caller":"traceutil/trace.go:171","msg":"trace[1081316316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:888; }","duration":"139.517556ms","start":"2026-01-24T02:40:37.548958Z","end":"2026-01-24T02:40:37.688475Z","steps":["trace[1081316316] 'range keys from in-memory index tree' (duration: 139.28062ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:37.68862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.558165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-24T02:40:37.688679Z","caller":"traceutil/trace.go:171","msg":"trace[290112347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:888; }","duration":"133.588206ms","start":"2026-01-24T02:40:37.555046Z","end":"2026-01-24T02:40:37.688634Z","steps":["trace[290112347] 'range keys from in-memory index tree' (duration: 133.456173ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:37.688781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.432359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-24T02:40:37.688799Z","caller":"traceutil/trace.go:171","msg":"trace[77934777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:888; }","duration":"127.46184ms","start":"2026-01-24T02:40:37.561333Z","end":"2026-01-24T02:40:37.688795Z","steps":["trace[77934777] 'range keys from in-memory index tree' (duration: 127.387988ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:41.335638Z","caller":"traceutil/trace.go:171","msg":"trace[906155815] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"139.525948ms","start":"2026-01-24T02:40:41.196098Z","end":"2026-01-24T02:40:41.335624Z","steps":["trace[906155815] 'read index received' (duration: 139.427136ms)","trace[906155815] 'applied index is now lower than readState.Index' (duration: 98.072µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:41.335664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:40.690392Z","time spent":"645.265705ms","remote":"127.0.0.1:37376","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-24T02:40:41.335779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.666662ms","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-01-24T02:40:41.3358Z","caller":"traceutil/trace.go:171","msg":"trace[418202154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"139.720974ms","start":"2026-01-24T02:40:41.196072Z","end":"2026-01-24T02:40:41.335793Z","steps":["trace[418202154] 'agreement among raft nodes before linearized reading' (duration: 139.609531ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:41.686345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.40899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-24T02:40:41.686413Z","caller":"traceutil/trace.go:171","msg":"trace[816056034] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"349.563965ms","start":"2026-01-24T02:40:41.336834Z","end":"2026-01-24T02:40:41.686398Z","steps":["trace[816056034] 'process raft request' (duration: 230.051842ms)","trace[816056034] 'compare' (duration: 119.214565ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:41.686501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:41.336812Z","time spent":"349.670168ms","remote":"127.0.0.1:37376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:40:41.706687Z","caller":"traceutil/trace.go:171","msg":"trace[2033688580] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:933; }","duration":"224.362327ms","start":"2026-01-24T02:40:41.482315Z","end":"2026-01-24T02:40:41.706677Z","steps":["trace[2033688580] 'read index received' (duration: 84.580263ms)","trace[2033688580] 'applied index is now lower than readState.Index' (duration: 139.781564ms)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:40:41.706871Z","caller":"traceutil/trace.go:171","msg":"trace[841499977] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"366.599239ms","start":"2026-01-24T02:40:41.340256Z","end":"2026-01-24T02:40:41.706855Z","steps":["trace[841499977] 'process raft request' (duration: 366.356223ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:41.706922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:41.340242Z","time spent":"366.6465ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:40:41.707106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.789099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-24T02:40:41.707135Z","caller":"traceutil/trace.go:171","msg":"trace[1057588971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"224.84001ms","start":"2026-01-24T02:40:41.482288Z","end":"2026-01-24T02:40:41.707128Z","steps":["trace[1057588971] 'agreement among raft nodes before linearized reading' (duration: 224.747107ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:47.882444Z","caller":"traceutil/trace.go:171","msg":"trace[2116679975] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"139.864816ms","start":"2026-01-24T02:40:47.742564Z","end":"2026-01-24T02:40:47.882429Z","steps":["trace[2116679975] 'read index received' (duration: 139.722652ms)","trace[2116679975] 'applied index is now lower than readState.Index' (duration: 141.504µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:47.882681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.100383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-24T02:40:47.882732Z","caller":"traceutil/trace.go:171","msg":"trace[1309737901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:915; }","duration":"140.178195ms","start":"2026-01-24T02:40:47.742546Z","end":"2026-01-24T02:40:47.882725Z","steps":["trace[1309737901] 'agreement among raft nodes before linearized reading' (duration: 140.044472ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:47.882965Z","caller":"traceutil/trace.go:171","msg":"trace[164604497] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"156.11458ms","start":"2026-01-24T02:40:47.726843Z","end":"2026-01-24T02:40:47.882957Z","steps":["trace[164604497] 'process raft request' (duration: 155.493433ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:48.787037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.754167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-24T02:40:48.787103Z","caller":"traceutil/trace.go:171","msg":"trace[2088373156] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"876.545479ms","start":"2026-01-24T02:40:47.910546Z","end":"2026-01-24T02:40:48.787091Z","steps":["trace[2088373156] 'process raft request' (duration: 629.669028ms)","trace[2088373156] 'compare' (duration: 246.650734ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:40:48.787133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:47.910538Z","time spent":"876.581749ms","remote":"127.0.0.1:37660","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-01-24T02:40:48.819845Z","caller":"traceutil/trace.go:171","msg":"trace[120027089] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:954; }","duration":"675.710682ms","start":"2026-01-24T02:40:48.144012Z","end":"2026-01-24T02:40:48.819723Z","steps":["trace[120027089] 'read index received' (duration: 396.11156ms)","trace[120027089] 'applied index is now lower than readState.Index' (duration: 279.598252ms)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:40:48.819787Z","caller":"traceutil/trace.go:171","msg":"trace[1505928916] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"831.021591ms","start":"2026-01-24T02:40:47.988752Z","end":"2026-01-24T02:40:48.819773Z","steps":["trace[1505928916] 'process raft request' (duration: 830.784815ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:48.820053Z","caller":"traceutil/trace.go:171","msg":"trace[794866038] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"811.296104ms","start":"2026-01-24T02:40:48.008748Z","end":"2026-01-24T02:40:48.820044Z","steps":["trace[794866038] 'process raft request' (duration: 810.933894ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:48.82027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:47.988735Z","time spent":"831.244228ms","remote":"127.0.0.1:37660","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-01-24T02:40:48.820236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:48.008734Z","time spent":"811.467788ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:40:48.820841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.814502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:48.820987Z","caller":"traceutil/trace.go:171","msg":"trace[2069638801] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:919; }","duration":"676.988776ms","start":"2026-01-24T02:40:48.143984Z","end":"2026-01-24T02:40:48.820973Z","steps":["trace[2069638801] 'agreement among raft nodes before linearized reading' (duration: 676.541714ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:48.821064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:48.143971Z","time spent":"677.079279ms","remote":"127.0.0.1:37520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-01-24T02:40:48.821376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.092837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:48.821422Z","caller":"traceutil/trace.go:171","msg":"trace[2055695948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"642.16261ms","start":"2026-01-24T02:40:48.179246Z","end":"2026-01-24T02:40:48.821409Z","steps":["trace[2055695948] 'agreement among raft nodes before linearized reading' (duration: 642.089668ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:48.821454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:48.179234Z","time spent":"642.213401ms","remote":"127.0.0.1:37352","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-01-24T02:40:50.131642Z","caller":"traceutil/trace.go:171","msg":"trace[1218545768] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"153.535999ms","start":"2026-01-24T02:40:49.97809Z","end":"2026-01-24T02:40:50.131626Z","steps":["trace[1218545768] 'read index received' (duration: 153.336954ms)","trace[1218545768] 'applied index is now lower than readState.Index' (duration: 198.445µs)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:40:50.132355Z","caller":"traceutil/trace.go:171","msg":"trace[2144874092] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"235.908182ms","start":"2026-01-24T02:40:49.896439Z","end":"2026-01-24T02:40:50.132347Z","steps":["trace[2144874092] 'process raft request' (duration: 235.051889ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:50.133053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.939087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-24T02:40:50.133112Z","caller":"traceutil/trace.go:171","msg":"trace[701964939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"155.011229ms","start":"2026-01-24T02:40:49.978085Z","end":"2026-01-24T02:40:50.133096Z","steps":["trace[701964939] 'agreement among raft nodes before linearized reading' (duration: 154.799613ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:40:55.055626Z","caller":"traceutil/trace.go:171","msg":"trace[777628082] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"206.084481ms","start":"2026-01-24T02:40:54.849516Z","end":"2026-01-24T02:40:55.055601Z","steps":["trace[777628082] 'process raft request' (duration: 178.533911ms)","trace[777628082] 'compare' (duration: 27.463087ms)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:40:55.06193Z","caller":"traceutil/trace.go:171","msg":"trace[1388347214] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"210.498311ms","start":"2026-01-24T02:40:54.851417Z","end":"2026-01-24T02:40:55.061915Z","steps":["trace[1388347214] 'process raft request' (duration: 210.314946ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:56.533942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208183845365258784,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-24T02:40:56.647791Z","caller":"traceutil/trace.go:171","msg":"trace[996076192] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"614.387087ms","start":"2026-01-24T02:40:56.033374Z","end":"2026-01-24T02:40:56.647761Z","steps":["trace[996076192] 'read index received' (duration: 614.170421ms)","trace[996076192] 'applied index is now lower than readState.Index' (duration: 215.756µs)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:40:56.647907Z","caller":"traceutil/trace.go:171","msg":"trace[30433685] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"622.311063ms","start":"2026-01-24T02:40:56.025586Z","end":"2026-01-24T02:40:56.647897Z","steps":["trace[30433685] 'process raft request' (duration: 622.013695ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:56.648019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:56.025572Z","time spent":"622.377756ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:40:56.648153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.228951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:40:56.648217Z","caller":"traceutil/trace.go:171","msg":"trace[1473173968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"568.325833ms","start":"2026-01-24T02:40:56.079874Z","end":"2026-01-24T02:40:56.6482Z","steps":["trace[1473173968] 'agreement among raft nodes before linearized reading' (duration: 568.225421ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:56.648253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:56.07986Z","time spent":"568.386596ms","remote":"127.0.0.1:37352","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-01-24T02:40:56.648436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.054766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-24T02:40:56.648463Z","caller":"traceutil/trace.go:171","msg":"trace[810062176] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:937; }","duration":"615.101087ms","start":"2026-01-24T02:40:56.033354Z","end":"2026-01-24T02:40:56.648455Z","steps":["trace[810062176] 'agreement among raft nodes before linearized reading' (duration: 615.050096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:56.64849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:56.033337Z","time spent":"615.145688ms","remote":"127.0.0.1:37540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-01-24T02:40:56.648514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.40133ms","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-01-24T02:40:56.648584Z","caller":"traceutil/trace.go:171","msg":"trace[695471251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"448.469141ms","start":"2026-01-24T02:40:56.200074Z","end":"2026-01-24T02:40:56.648543Z","steps":["trace[695471251] 'agreement among raft nodes before linearized reading' (duration: 448.346897ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:56.648616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:56.200058Z","time spent":"448.550754ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-24T02:40:56.648804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.572039ms","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-01-24T02:40:56.648833Z","caller":"traceutil/trace.go:171","msg":"trace[1529801964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:937; }","duration":"432.623469ms","start":"2026-01-24T02:40:56.216201Z","end":"2026-01-24T02:40:56.648825Z","steps":["trace[1529801964] 'agreement among raft nodes before linearized reading' (duration: 432.533457ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:56.648858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:56.216189Z","time spent":"432.663341ms","remote":"127.0.0.1:37660","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":"warn","ts":"2026-01-24T02:40:57.676527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.374168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-24T02:40:57.676576Z","caller":"traceutil/trace.go:171","msg":"trace[1673835836] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:942; }","duration":"547.453141ms","start":"2026-01-24T02:40:57.129109Z","end":"2026-01-24T02:40:57.676563Z","steps":["trace[1673835836] 'count revisions from in-memory index tree' (duration: 547.319217ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:57.676596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:57.129095Z","time spent":"547.495822ms","remote":"127.0.0.1:37462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-01-24T02:40:59.129846Z","caller":"traceutil/trace.go:171","msg":"trace[158439664] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"431.065668ms","start":"2026-01-24T02:40:58.698764Z","end":"2026-01-24T02:40:59.12983Z","steps":["trace[158439664] 'read index received' (duration: 430.887113ms)","trace[158439664] 'applied index is now lower than readState.Index' (duration: 177.845µs)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:40:59.130029Z","caller":"traceutil/trace.go:171","msg":"trace[677188246] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"465.068274ms","start":"2026-01-24T02:40:58.664954Z","end":"2026-01-24T02:40:59.130022Z","steps":["trace[677188246] 'process raft request' (duration: 464.744185ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:59.130101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:58.664941Z","time spent":"465.111796ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:40:59.130283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.52303ms","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-01-24T02:40:59.13031Z","caller":"traceutil/trace.go:171","msg":"trace[2036927855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"431.575842ms","start":"2026-01-24T02:40:58.698728Z","end":"2026-01-24T02:40:59.130304Z","steps":["trace[2036927855] 'agreement among raft nodes before linearized reading' (duration: 431.490899ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:59.13033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:58.6987Z","time spent":"431.624903ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-24T02:40:59.130432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.587889ms","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-01-24T02:40:59.130455Z","caller":"traceutil/trace.go:171","msg":"trace[1755814036] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:947; }","duration":"330.637211ms","start":"2026-01-24T02:40:58.799812Z","end":"2026-01-24T02:40:59.130449Z","steps":["trace[1755814036] 'agreement among raft nodes before linearized reading' (duration: 330.59867ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:40:59.130473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:40:58.799789Z","time spent":"330.678362ms","remote":"127.0.0.1:37804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-01-24T02:40:59.130884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.982274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-24T02:40:59.130911Z","caller":"traceutil/trace.go:171","msg":"trace[289752779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"271.009705ms","start":"2026-01-24T02:40:58.859895Z","end":"2026-01-24T02:40:59.130905Z","steps":["trace[289752779] 'agreement among raft nodes before linearized reading' (duration: 270.933903ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:41:02.978393Z","caller":"traceutil/trace.go:171","msg":"trace[1373512973] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"213.270883ms","start":"2026-01-24T02:41:02.765093Z","end":"2026-01-24T02:41:02.978364Z","steps":["trace[1373512973] 'process raft request' (duration: 213.15448ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:41:03.285376Z","caller":"traceutil/trace.go:171","msg":"trace[39278672] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1016; }","duration":"102.046421ms","start":"2026-01-24T02:41:03.183314Z","end":"2026-01-24T02:41:03.285361Z","steps":["trace[39278672] 'read index received' (duration: 20.663633ms)","trace[39278672] 'applied index is now lower than readState.Index' (duration: 81.382148ms)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:41:03.285456Z","caller":"traceutil/trace.go:171","msg":"trace[1476251693] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"105.30108ms","start":"2026-01-24T02:41:03.180149Z","end":"2026-01-24T02:41:03.28545Z","steps":["trace[1476251693] 'process raft request' (duration: 23.820719ms)","trace[1476251693] 'compare' (duration: 81.269505ms)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:41:03.285644Z","caller":"traceutil/trace.go:171","msg":"trace[1796989682] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"104.22903ms","start":"2026-01-24T02:41:03.181408Z","end":"2026-01-24T02:41:03.285637Z","steps":["trace[1796989682] 'process raft request' (duration: 103.926232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:03.285797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.470133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-24T02:41:03.285822Z","caller":"traceutil/trace.go:171","msg":"trace[2013997520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"102.517994ms","start":"2026-01-24T02:41:03.183297Z","end":"2026-01-24T02:41:03.285815Z","steps":["trace[2013997520] 'agreement among raft nodes before linearized reading' (duration: 102.433312ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:03.285913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.761505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-24T02:41:03.28593Z","caller":"traceutil/trace.go:171","msg":"trace[476722752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"100.789317ms","start":"2026-01-24T02:41:03.185137Z","end":"2026-01-24T02:41:03.285926Z","steps":["trace[476722752] 'agreement among raft nodes before linearized reading' (duration: 100.745326ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:03.875795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.722799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-24T02:41:03.875953Z","caller":"traceutil/trace.go:171","msg":"trace[1572494789] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"578.959689ms","start":"2026-01-24T02:41:03.296977Z","end":"2026-01-24T02:41:03.875937Z","steps":["trace[1572494789] 'process raft request' (duration: 578.905698ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:03.87602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:03.296971Z","time spent":"579.019661ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:41:03.876293Z","caller":"traceutil/trace.go:171","msg":"trace[370339212] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"581.94356ms","start":"2026-01-24T02:41:03.294335Z","end":"2026-01-24T02:41:03.876279Z","steps":["trace[370339212] 'process raft request' (duration: 376.692157ms)","trace[370339212] 'compare' (duration: 204.443531ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:41:03.876354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:03.294319Z","time spent":"582.006362ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:41:11.955199Z","caller":"traceutil/trace.go:171","msg":"trace[1235977608] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"110.868591ms","start":"2026-01-24T02:41:11.844309Z","end":"2026-01-24T02:41:11.955178Z","steps":["trace[1235977608] 'process raft request' (duration: 110.492831ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:41:12.351173Z","caller":"traceutil/trace.go:171","msg":"trace[927809849] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"381.367664ms","start":"2026-01-24T02:41:11.969586Z","end":"2026-01-24T02:41:12.350954Z","steps":["trace[927809849] 'process raft request' (duration: 378.389083ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:12.351332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:11.969574Z","time spent":"381.689713ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:41:12.353931Z","caller":"traceutil/trace.go:171","msg":"trace[1974675199] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"267.221633ms","start":"2026-01-24T02:41:12.086701Z","end":"2026-01-24T02:41:12.353923Z","steps":["trace[1974675199] 'read index received' (duration: 261.285631ms)","trace[1974675199] 'applied index is now lower than readState.Index' (duration: 5.935672ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:41:12.353983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.283446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:41:12.354002Z","caller":"traceutil/trace.go:171","msg":"trace[2010487030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"267.341527ms","start":"2026-01-24T02:41:12.086655Z","end":"2026-01-24T02:41:12.353997Z","steps":["trace[2010487030] 'agreement among raft nodes before linearized reading' (duration: 267.298276ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:41:12.354109Z","caller":"traceutil/trace.go:171","msg":"trace[234195334] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"383.877162ms","start":"2026-01-24T02:41:11.970226Z","end":"2026-01-24T02:41:12.354103Z","steps":["trace[234195334] 'process raft request' (duration: 383.661226ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:12.354141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:11.970217Z","time spent":"383.906544ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:41:16.903702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208183845365259027,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-24T02:41:17.189254Z","caller":"traceutil/trace.go:171","msg":"trace[497141853] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"815.882395ms","start":"2026-01-24T02:41:16.37335Z","end":"2026-01-24T02:41:17.189232Z","steps":["trace[497141853] 'process raft request' (duration: 815.756832ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:17.189386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:16.373331Z","time spent":"815.987958ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:41:18.414814Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.211989582s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-24T02:41:18.68381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.890604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-24T02:41:18.683882Z","caller":"traceutil/trace.go:171","msg":"trace[271013932] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"2.280205045s","start":"2026-01-24T02:41:16.403663Z","end":"2026-01-24T02:41:18.683868Z","steps":["trace[271013932] 'read index received' (duration: 786.297739ms)","trace[271013932] 'applied index is now lower than readState.Index' (duration: 1.493906326s)"],"step_count":2} {"level":"info","ts":"2026-01-24T02:41:18.684018Z","caller":"traceutil/trace.go:171","msg":"trace[167148211] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.871117815s","start":"2026-01-24T02:41:16.812892Z","end":"2026-01-24T02:41:18.68401Z","steps":["trace[167148211] 'process raft request' (duration: 1.654975265s)","trace[167148211] 'compare' (duration: 215.724039ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:41:18.68406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:16.812869Z","time spent":"1.871168296s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:41:18.684253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.280586966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-24T02:41:18.684279Z","caller":"traceutil/trace.go:171","msg":"trace[1940310011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1058; }","duration":"2.280650897s","start":"2026-01-24T02:41:16.40362Z","end":"2026-01-24T02:41:18.684271Z","steps":["trace[1940310011] 'agreement among raft nodes before linearized reading' (duration: 2.280562465s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:18.684303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:16.403595Z","time spent":"2.280702018s","remote":"127.0.0.1:37660","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":"warn","ts":"2026-01-24T02:41:18.684452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.96854621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-01-24T02:41:18.68447Z","caller":"traceutil/trace.go:171","msg":"trace[1848931690] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1058; }","duration":"1.968599442s","start":"2026-01-24T02:41:16.715865Z","end":"2026-01-24T02:41:18.684465Z","steps":["trace[1848931690] 'agreement among raft nodes before linearized reading' (duration: 1.96854742s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:18.684487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:16.715844Z","time spent":"1.968638703s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-01-24T02:41:18.684649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.189285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-24T02:41:18.684667Z","caller":"traceutil/trace.go:171","msg":"trace[1093619076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1058; }","duration":"503.290057ms","start":"2026-01-24T02:41:18.181372Z","end":"2026-01-24T02:41:18.684662Z","steps":["trace[1093619076] 'agreement among raft nodes before linearized reading' (duration: 503.235196ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:18.684683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.18135Z","time spent":"503.329759ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-24T02:41:18.6848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106045677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"warn","ts":"2026-01-24T02:41:18.686174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339472278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-24T02:41:18.686255Z","caller":"traceutil/trace.go:171","msg":"trace[840665582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1058; }","duration":"1.33957781s","start":"2026-01-24T02:41:17.346662Z","end":"2026-01-24T02:41:18.68624Z","steps":["trace[840665582] 'agreement among raft nodes before linearized reading' (duration: 1.339438396s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:18.686285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:17.346652Z","time spent":"1.339626572s","remote":"127.0.0.1:37660","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":"warn","ts":"2026-01-24T02:41:18.686622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347428694s","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-01-24T02:41:18.686654Z","caller":"traceutil/trace.go:171","msg":"trace[934921926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1058; }","duration":"1.347489506s","start":"2026-01-24T02:41:17.339155Z","end":"2026-01-24T02:41:18.686645Z","steps":["trace[934921926] 'agreement among raft nodes before linearized reading' (duration: 1.347402104s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:18.686677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:17.339138Z","time spent":"1.347530487s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-24T02:41:18.686912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605830196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:41:18.686938Z","caller":"traceutil/trace.go:171","msg":"trace[145340493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"1.605896899s","start":"2026-01-24T02:41:17.081033Z","end":"2026-01-24T02:41:18.68693Z","steps":["trace[145340493] 'agreement among raft nodes before linearized reading' (duration: 1.605734265s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:18.686963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:17.081014Z","time spent":"1.60593938s","remote":"127.0.0.1:37352","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-01-24T02:41:18.68708Z","caller":"traceutil/trace.go:171","msg":"trace[781055904] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1058; }","duration":"1.106096578s","start":"2026-01-24T02:41:17.578716Z","end":"2026-01-24T02:41:18.684813Z","steps":["trace[781055904] 'agreement among raft nodes before linearized reading' (duration: 1.106035907s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:18.687158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:17.578698Z","time spent":"1.108441392s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-24T02:41:18.688441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.728801569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-01-24T02:41:18.688519Z","caller":"traceutil/trace.go:171","msg":"trace[1254599239] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1058; }","duration":"1.728905282s","start":"2026-01-24T02:41:16.959588Z","end":"2026-01-24T02:41:18.688493Z","steps":["trace[1254599239] 'agreement among raft nodes before linearized reading' (duration: 1.725874159s)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:18.68856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:16.95957Z","time spent":"1.728980253s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-24T02:41:19.148862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.757876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-24T02:41:19.149792Z","caller":"traceutil/trace.go:171","msg":"trace[736596558] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"452.073229ms","start":"2026-01-24T02:41:18.697697Z","end":"2026-01-24T02:41:19.14977Z","steps":["trace[736596558] 'process raft request' (duration: 259.331397ms)","trace[736596558] 'compare' (duration: 191.665353ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:41:19.14994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.697683Z","time spent":"452.214023ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:41:19.154137Z","caller":"traceutil/trace.go:171","msg":"trace[1806056204] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1102; }","duration":"449.286423ms","start":"2026-01-24T02:41:18.704832Z","end":"2026-01-24T02:41:19.154118Z","steps":["trace[1806056204] 'read index received' (duration: 252.207492ms)","trace[1806056204] 'applied index is now lower than readState.Index' (duration: 197.078301ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:41:19.154257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.400916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:41:19.15428Z","caller":"traceutil/trace.go:171","msg":"trace[1893155268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"449.464568ms","start":"2026-01-24T02:41:18.704808Z","end":"2026-01-24T02:41:19.154273Z","steps":["trace[1893155268] 'agreement among raft nodes before linearized reading' (duration: 449.385056ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:19.154305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.7048Z","time spent":"449.499179ms","remote":"127.0.0.1:37352","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-01-24T02:41:19.154549Z","caller":"traceutil/trace.go:171","msg":"trace[969390646] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"454.064474ms","start":"2026-01-24T02:41:18.700476Z","end":"2026-01-24T02:41:19.154541Z","steps":["trace[969390646] 'process raft request' (duration: 453.340414ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:19.154667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.700469Z","time spent":"454.098144ms","remote":"127.0.0.1:37660","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-01-24T02:41:19.154764Z","caller":"traceutil/trace.go:171","msg":"trace[854428141] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"454.063363ms","start":"2026-01-24T02:41:18.700693Z","end":"2026-01-24T02:41:19.154757Z","steps":["trace[854428141] 'process raft request' (duration: 453.264922ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:19.154868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.700687Z","time spent":"454.093464ms","remote":"127.0.0.1:37660","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-01-24T02:41:19.155105Z","caller":"traceutil/trace.go:171","msg":"trace[1573050108] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"450.233499ms","start":"2026-01-24T02:41:18.704863Z","end":"2026-01-24T02:41:19.155097Z","steps":["trace[1573050108] 'process raft request' (duration: 449.132579ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:19.155157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.704858Z","time spent":"450.274381ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:41:19.15538Z","caller":"traceutil/trace.go:171","msg":"trace[1147056686] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"450.132746ms","start":"2026-01-24T02:41:18.705239Z","end":"2026-01-24T02:41:19.155371Z","steps":["trace[1147056686] 'process raft request' (duration: 448.7969ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:19.155429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.705225Z","time spent":"450.179098ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:41:19.155878Z","caller":"traceutil/trace.go:171","msg":"trace[804234943] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"449.808788ms","start":"2026-01-24T02:41:18.706058Z","end":"2026-01-24T02:41:19.155867Z","steps":["trace[804234943] 'process raft request' (duration: 448.009869ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:41:19.156496Z","caller":"traceutil/trace.go:171","msg":"trace[2069580292] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"430.546182ms","start":"2026-01-24T02:41:18.72592Z","end":"2026-01-24T02:41:19.156466Z","steps":["trace[2069580292] 'process raft request' (duration: 428.174117ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:41:19.156697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.706052Z","time spent":"449.864309ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:41:19.159544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:41:18.725906Z","time spent":"430.729488ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-24T02:44:27.414562Z","caller":"traceutil/trace.go:171","msg":"trace[1224498153] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"104.783143ms","start":"2026-01-24T02:44:27.30976Z","end":"2026-01-24T02:44:27.414543Z","steps":["trace[1224498153] 'process raft request' (duration: 104.6665ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:44:28.457369Z","caller":"traceutil/trace.go:171","msg":"trace[1682692308] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"325.36258ms","start":"2026-01-24T02:44:28.131976Z","end":"2026-01-24T02:44:28.457338Z","steps":["trace[1682692308] 'read index received' (duration: 325.117673ms)","trace[1682692308] 'applied index is now lower than readState.Index' (duration: 243.707µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:44:28.458017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.009587ms","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-01-24T02:44:28.4581Z","caller":"traceutil/trace.go:171","msg":"trace[886432444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"326.140321ms","start":"2026-01-24T02:44:28.13194Z","end":"2026-01-24T02:44:28.45808Z","steps":["trace[886432444] 'agreement among raft nodes before linearized reading' (duration: 325.807292ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:28.45852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:44:28.131923Z","time spent":"326.336896ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-24T02:44:28.458945Z","caller":"traceutil/trace.go:171","msg":"trace[853806368] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"328.839905ms","start":"2026-01-24T02:44:28.130087Z","end":"2026-01-24T02:44:28.458927Z","steps":["trace[853806368] 'process raft request' (duration: 327.076147ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:28.4593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.097783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-01-24T02:44:28.459432Z","caller":"traceutil/trace.go:171","msg":"trace[721397766] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1862; }","duration":"236.499932ms","start":"2026-01-24T02:44:28.222901Z","end":"2026-01-24T02:44:28.459401Z","steps":["trace[721397766] 'agreement among raft nodes before linearized reading' (duration: 235.045122ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:28.45934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:44:28.130069Z","time spent":"328.966798ms","remote":"127.0.0.1:37660","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-01-24T02:44:28.459926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.66517ms","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-01-24T02:44:28.460051Z","caller":"traceutil/trace.go:171","msg":"trace[408636996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1862; }","duration":"100.810964ms","start":"2026-01-24T02:44:28.359223Z","end":"2026-01-24T02:44:28.460034Z","steps":["trace[408636996] 'agreement among raft nodes before linearized reading' (duration: 100.606969ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:28.460449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.865633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-24T02:44:28.461207Z","caller":"traceutil/trace.go:171","msg":"trace[1663665208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"102.662315ms","start":"2026-01-24T02:44:28.358525Z","end":"2026-01-24T02:44:28.461187Z","steps":["trace[1663665208] 'agreement among raft nodes before linearized reading' (duration: 101.851273ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:28.460911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.472243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-01-24T02:44:28.464009Z","caller":"traceutil/trace.go:171","msg":"trace[1983514621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"115.614959ms","start":"2026-01-24T02:44:28.348383Z","end":"2026-01-24T02:44:28.463998Z","steps":["trace[1983514621] 'agreement among raft nodes before linearized reading' (duration: 112.442372ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:44:44.834499Z","caller":"traceutil/trace.go:171","msg":"trace[1209002972] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"234.7984ms","start":"2026-01-24T02:44:44.599675Z","end":"2026-01-24T02:44:44.834473Z","steps":["trace[1209002972] 'process raft request' (duration: 234.635405ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:44:44.836721Z","caller":"traceutil/trace.go:171","msg":"trace[1056940200] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"231.255393ms","start":"2026-01-24T02:44:44.605044Z","end":"2026-01-24T02:44:44.836299Z","steps":["trace[1056940200] 'process raft request' (duration: 230.74624ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:44.837068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.853068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-01-24T02:44:44.837267Z","caller":"traceutil/trace.go:171","msg":"trace[617830525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1935; }","duration":"232.118317ms","start":"2026-01-24T02:44:44.605091Z","end":"2026-01-24T02:44:44.837209Z","steps":["trace[617830525] 'agreement among raft nodes before linearized reading' (duration: 231.785658ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:44:44.835943Z","caller":"traceutil/trace.go:171","msg":"trace[1553514874] linearizableReadLoop","detail":"{readStateIndex:2021; appliedIndex:2020; }","duration":"227.721287ms","start":"2026-01-24T02:44:44.608205Z","end":"2026-01-24T02:44:44.835926Z","steps":["trace[1553514874] 'read index received' (duration: 226.993497ms)","trace[1553514874] 'applied index is now lower than readState.Index' (duration: 726.86µs)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:44:44.837784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.745245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-01-24T02:44:44.837983Z","caller":"traceutil/trace.go:171","msg":"trace[1989312651] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1935; }","duration":"228.951531ms","start":"2026-01-24T02:44:44.609012Z","end":"2026-01-24T02:44:44.837963Z","steps":["trace[1989312651] 'agreement among raft nodes before linearized reading' (duration: 228.662563ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:44.838026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.80583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:1 size:1383"} {"level":"warn","ts":"2026-01-24T02:44:44.838777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.322387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-01-24T02:44:44.838856Z","caller":"traceutil/trace.go:171","msg":"trace[371082740] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1935; }","duration":"197.386649ms","start":"2026-01-24T02:44:44.641433Z","end":"2026-01-24T02:44:44.83882Z","steps":["trace[371082740] 'agreement among raft nodes before linearized reading' (duration: 197.183984ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:44.839531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.108869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-01-24T02:44:44.839598Z","caller":"traceutil/trace.go:171","msg":"trace[378109142] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1935; }","duration":"198.183771ms","start":"2026-01-24T02:44:44.641396Z","end":"2026-01-24T02:44:44.83958Z","steps":["trace[378109142] 'agreement among raft nodes before linearized reading' (duration: 198.090789ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:44.840006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.856259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-01-24T02:44:44.840072Z","caller":"traceutil/trace.go:171","msg":"trace[1334781573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1935; }","duration":"202.94932ms","start":"2026-01-24T02:44:44.637116Z","end":"2026-01-24T02:44:44.840065Z","steps":["trace[1334781573] 'agreement among raft nodes before linearized reading' (duration: 202.846628ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:44.840381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.960705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" ","response":"range_response_count:1 size:578"} {"level":"info","ts":"2026-01-24T02:44:44.840486Z","caller":"traceutil/trace.go:171","msg":"trace[2012868803] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:1; response_revision:1935; }","duration":"220.063308ms","start":"2026-01-24T02:44:44.620414Z","end":"2026-01-24T02:44:44.840477Z","steps":["trace[2012868803] 'agreement among raft nodes before linearized reading' (duration: 219.939734ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:44:44.838523Z","caller":"traceutil/trace.go:171","msg":"trace[966957175] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:1935; }","duration":"187.327915ms","start":"2026-01-24T02:44:44.651173Z","end":"2026-01-24T02:44:44.8385Z","steps":["trace[966957175] 'agreement among raft nodes before linearized reading' (duration: 186.678137ms)"],"step_count":1} {"level":"info","ts":"2026-01-24T02:44:45.360257Z","caller":"traceutil/trace.go:171","msg":"trace[757833879] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"339.091645ms","start":"2026-01-24T02:44:45.021148Z","end":"2026-01-24T02:44:45.360239Z","steps":["trace[757833879] 'process raft request' (duration: 338.977022ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:45.360608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:44:45.021121Z","time spent":"339.420633ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-24T02:44:45.361763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.846165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-01-24T02:44:45.361942Z","caller":"traceutil/trace.go:171","msg":"trace[162734912] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:1943; }","duration":"329.02668ms","start":"2026-01-24T02:44:45.032906Z","end":"2026-01-24T02:44:45.361933Z","steps":["trace[162734912] 'agreement among raft nodes before linearized reading' (duration: 328.820835ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:45.36224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:44:45.032878Z","time spent":"329.348049ms","remote":"127.0.0.1:37734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":688,"request content":"key:\"/registry/clusterroles/system:kubelet-api-admin\" "} {"level":"info","ts":"2026-01-24T02:44:45.361367Z","caller":"traceutil/trace.go:171","msg":"trace[1627001634] linearizableReadLoop","detail":"{readStateIndex:2029; appliedIndex:2029; }","duration":"328.440974ms","start":"2026-01-24T02:44:45.03291Z","end":"2026-01-24T02:44:45.361351Z","steps":["trace[1627001634] 'read index received' (duration: 327.137899ms)","trace[1627001634] 'applied index is now lower than readState.Index' (duration: 1.299305ms)"],"step_count":2} {"level":"warn","ts":"2026-01-24T02:44:45.365152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.28826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-24T02:44:45.365249Z","caller":"traceutil/trace.go:171","msg":"trace[1243801338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1943; }","duration":"283.417483ms","start":"2026-01-24T02:44:45.081821Z","end":"2026-01-24T02:44:45.365239Z","steps":["trace[1243801338] 'agreement among raft nodes before linearized reading' (duration: 283.30691ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:45.370114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.621933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:1 size:634"} {"level":"info","ts":"2026-01-24T02:44:45.370233Z","caller":"traceutil/trace.go:171","msg":"trace[54409490] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:1; response_revision:1943; }","duration":"323.773646ms","start":"2026-01-24T02:44:45.046426Z","end":"2026-01-24T02:44:45.3702Z","steps":["trace[54409490] 'agreement among raft nodes before linearized reading' (duration: 318.647486ms)"],"step_count":1} {"level":"warn","ts":"2026-01-24T02:44:45.37032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-24T02:44:45.046416Z","time spent":"323.887099ms","remote":"127.0.0.1:37734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":658,"request content":"key:\"/registry/clusterroles/system:monitoring\" "}