{"level":"warn","ts":"2026-05-05T18:17:32.145531Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T18:17:32.145651Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-05-05T18:17:32.145738Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T18:17:32.145755Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-05T18:17:32.145778Z","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-05-05T18:17:32.147139Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-05-05T18:17:32.147479Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-05-05T18:17:32.151537Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.626825ms"} {"level":"info","ts":"2026-05-05T18:17:32.157381Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-05-05T18:17:32.157445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T18:17:32.157461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-05-05T18:17:32.15747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T18:17:32.15748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-05-05T18:17:32.15751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-05-05T18:17:32.166129Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T18:17:32.167985Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T18:17:32.169346Z","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-05-05T18:17:32.172533Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T18:17:32.17389Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T18:17:32.174113Z","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-05-05T18:17:32.174186Z","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-05-05T18:17:32.174202Z","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-05-05T18:17:32.174362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-05-05T18:17:32.177869Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-05T18:17:32.179411Z","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-05-05T18:17:32.179537Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-05T18:17:32.179574Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-05T18:17:32.179675Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T18:17:32.179722Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T18:17:32.958148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T18:17:32.958265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T18:17:32.958296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-05-05T18:17:32.958325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-05-05T18:17:32.958343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-05T18:17:32.958365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-05-05T18:17:32.958384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-05T18:17:32.959508Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T18:17:32.96015Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T18:17:32.960262Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T18:17:32.960307Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T18:17:32.960769Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T18:17:32.960957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T18:17:32.961055Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T18:17:32.960449Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T18:17:32.961416Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T18:17:32.962578Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-05-05T18:17:32.963363Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T18:17:35.764594Z","caller":"traceutil/trace.go:171","msg":"trace[1634745527] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"221.437213ms","start":"2026-05-05T18:17:35.54312Z","end":"2026-05-05T18:17:35.764557Z","steps":["trace[1634745527] 'process raft request' (duration: 169.973175ms)","trace[1634745527] 'compare' (duration: 51.313694ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:17:48.698968Z","caller":"traceutil/trace.go:171","msg":"trace[140914260] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"116.891374ms","start":"2026-05-05T18:17:48.582059Z","end":"2026-05-05T18:17:48.69895Z","steps":["trace[140914260] 'process raft request' (duration: 116.77804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:49.752922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.160282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:17:49.752974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.736407ms","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-05-05T18:17:49.75305Z","caller":"traceutil/trace.go:171","msg":"trace[1469596608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"388.399279ms","start":"2026-05-05T18:17:49.364638Z","end":"2026-05-05T18:17:49.753037Z","steps":["trace[1469596608] 'range keys from in-memory index tree' (duration: 388.055439ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:17:49.753058Z","caller":"traceutil/trace.go:171","msg":"trace[1006574380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"768.84671ms","start":"2026-05-05T18:17:48.984197Z","end":"2026-05-05T18:17:49.753043Z","steps":["trace[1006574380] 'range keys from in-memory index tree' (duration: 768.634874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:49.753076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:49.364619Z","time spent":"388.450201ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:17:49.753096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:48.984181Z","time spent":"768.907591ms","remote":"127.0.0.1:36730","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-05-05T18:17:49.753237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.193819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-05T18:17:49.753333Z","caller":"traceutil/trace.go:171","msg":"trace[1203202142] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:407; }","duration":"724.357524ms","start":"2026-05-05T18:17:49.028948Z","end":"2026-05-05T18:17:49.753305Z","steps":["trace[1203202142] 'range keys from in-memory index tree' (duration: 724.077166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:49.75339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:49.028925Z","time spent":"724.449396ms","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-05T18:17:50.409841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741890235990170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T18:17:50.498591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.763105ms","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-05-05T18:17:50.498711Z","caller":"traceutil/trace.go:171","msg":"trace[290635209] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:408; }","duration":"526.905478ms","start":"2026-05-05T18:17:49.971787Z","end":"2026-05-05T18:17:50.498692Z","steps":["trace[290635209] 'range keys from in-memory index tree' (duration: 526.747863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:50.910265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741890235990170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T18:17:51.411236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741890235990170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T18:17:51.427132Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.534038533s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T18:17:51.427593Z","caller":"traceutil/trace.go:171","msg":"trace[1536127745] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.666441162s","start":"2026-05-05T18:17:49.761129Z","end":"2026-05-05T18:17:51.42757Z","steps":["trace[1536127745] 'process raft request' (duration: 1.666218226s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:51.428164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:49.761111Z","time spent":"1.666536305s","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:17:53.119867Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.692588807s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T18:17:53.235104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.990054ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T18:17:53.235213Z","caller":"traceutil/trace.go:171","msg":"trace[31594111] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:423; }","duration":"3.326338634s","start":"2026-05-05T18:17:49.908858Z","end":"2026-05-05T18:17:53.235196Z","steps":["trace[31594111] 'read index received' (duration: 1.518467245s)","trace[31594111] 'applied index is now lower than readState.Index' (duration: 1.807869729s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:17:53.235398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871551638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:17:53.235436Z","caller":"traceutil/trace.go:171","msg":"trace[1169014130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"1.87163198s","start":"2026-05-05T18:17:51.363791Z","end":"2026-05-05T18:17:53.235423Z","steps":["trace[1169014130] 'agreement among raft nodes before linearized reading' (duration: 1.871570659s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:53.235423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.326545049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-05-05T18:17:53.235482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:51.363769Z","time spent":"1.871701242s","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:17:53.235483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.803556557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-05T18:17:53.235548Z","caller":"traceutil/trace.go:171","msg":"trace[458559256] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:409; }","duration":"1.80363959s","start":"2026-05-05T18:17:51.431893Z","end":"2026-05-05T18:17:53.235532Z","steps":["trace[458559256] 'agreement among raft nodes before linearized reading' (duration: 1.803466385s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:53.235551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.530429637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T18:17:53.235581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:51.431827Z","time spent":"1.803747952s","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-05-05T18:17:53.235579Z","caller":"traceutil/trace.go:171","msg":"trace[902319003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"2.530498519s","start":"2026-05-05T18:17:50.705073Z","end":"2026-05-05T18:17:53.235571Z","steps":["trace[902319003] 'agreement among raft nodes before linearized reading' (duration: 2.530334294s)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:17:53.235505Z","caller":"traceutil/trace.go:171","msg":"trace[241872137] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:409; }","duration":"3.326685793s","start":"2026-05-05T18:17:49.908795Z","end":"2026-05-05T18:17:53.235481Z","steps":["trace[241872137] 'agreement among raft nodes before linearized reading' (duration: 3.326512788s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:53.235622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:50.705049Z","time spent":"2.53056532s","remote":"127.0.0.1:36730","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-05-05T18:17:53.235624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402049105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T18:17:53.235643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.193501564s","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-05-05T18:17:53.235692Z","caller":"traceutil/trace.go:171","msg":"trace[910033264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:409; }","duration":"1.402121666s","start":"2026-05-05T18:17:51.833542Z","end":"2026-05-05T18:17:53.235663Z","steps":["trace[910033264] 'agreement among raft nodes before linearized reading' (duration: 1.401874089s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:53.235546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.147010655s","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-05-05T18:17:53.235734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:51.833527Z","time spent":"1.402198709s","remote":"127.0.0.1:36730","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-05-05T18:17:53.235744Z","caller":"traceutil/trace.go:171","msg":"trace[512582856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"3.193640327s","start":"2026-05-05T18:17:50.042071Z","end":"2026-05-05T18:17:53.235711Z","steps":["trace[512582856] 'agreement among raft nodes before linearized reading' (duration: 3.193258487s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:53.23579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:50.04205Z","time spent":"3.19372924s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T18:17:53.235795Z","caller":"traceutil/trace.go:171","msg":"trace[1287606054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"3.147295253s","start":"2026-05-05T18:17:50.088454Z","end":"2026-05-05T18:17:53.23575Z","steps":["trace[1287606054] 'agreement among raft nodes before linearized reading' (duration: 3.146975504s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:53.235848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:50.088431Z","time spent":"3.147400605s","remote":"127.0.0.1:36730","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-05-05T18:17:53.235673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:49.908766Z","time spent":"3.326865418s","remote":"127.0.0.1:36894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-05-05T18:17:59.007202Z","caller":"traceutil/trace.go:171","msg":"trace[1557323105] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"442.24573ms","start":"2026-05-05T18:17:58.564931Z","end":"2026-05-05T18:17:59.007176Z","steps":["trace[1557323105] 'process raft request' (duration: 442.070895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:17:59.007344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:17:58.56491Z","time spent":"442.361763ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:18:08.365436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.142717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:18:08.365917Z","caller":"traceutil/trace.go:171","msg":"trace[1547824011] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"880.348831ms","start":"2026-05-05T18:18:07.485537Z","end":"2026-05-05T18:18:08.365886Z","steps":["trace[1547824011] 'process raft request' (duration: 880.009622ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:08.36596Z","caller":"traceutil/trace.go:171","msg":"trace[584790634] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"864.969364ms","start":"2026-05-05T18:18:07.500955Z","end":"2026-05-05T18:18:08.365924Z","steps":["trace[584790634] 'process raft request' (duration: 864.793359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:08.36606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:07.48552Z","time spent":"880.475716ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:18:08.366124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:07.500923Z","time spent":"865.119119ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:18:08.365706Z","caller":"traceutil/trace.go:171","msg":"trace[854341078] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"889.118664ms","start":"2026-05-05T18:18:07.476541Z","end":"2026-05-05T18:18:08.36566Z","steps":["trace[854341078] 'process raft request' (duration: 65.597708ms)","trace[854341078] 'compare' (duration: 823.011292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:18:08.366954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:07.476517Z","time spent":"890.318437ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:18:09.145554Z","caller":"traceutil/trace.go:171","msg":"trace[130638328] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"456.143024ms","start":"2026-05-05T18:18:08.68939Z","end":"2026-05-05T18:18:09.145533Z","steps":["trace[130638328] 'process raft request' (duration: 448.618345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:09.145673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:08.689372Z","time spent":"456.245897ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:18:09.145859Z","caller":"traceutil/trace.go:171","msg":"trace[1691303265] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"455.289401ms","start":"2026-05-05T18:18:08.690557Z","end":"2026-05-05T18:18:09.145847Z","steps":["trace[1691303265] 'process raft request' (duration: 454.803379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:09.145929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:08.690547Z","time spent":"455.342103ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:18:09.146234Z","caller":"traceutil/trace.go:171","msg":"trace[1887168351] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"449.736613ms","start":"2026-05-05T18:18:08.69648Z","end":"2026-05-05T18:18:09.146217Z","steps":["trace[1887168351] 'process raft request' (duration: 449.60708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:09.146377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:08.696466Z","time spent":"449.834047ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:18:15.127445Z","caller":"traceutil/trace.go:171","msg":"trace[1092690842] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"121.844178ms","start":"2026-05-05T18:18:15.005576Z","end":"2026-05-05T18:18:15.12742Z","steps":["trace[1092690842] 'process raft request' (duration: 121.694544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:15.864185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741890235990619,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T18:18:15.928717Z","caller":"traceutil/trace.go:171","msg":"trace[949833979] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"646.503487ms","start":"2026-05-05T18:18:15.282191Z","end":"2026-05-05T18:18:15.928694Z","steps":["trace[949833979] 'process raft request' (duration: 646.014304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:15.928845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:15.282168Z","time spent":"646.61038ms","remote":"127.0.0.1:36580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:18:15.935235Z","caller":"traceutil/trace.go:171","msg":"trace[995035478] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"571.737245ms","start":"2026-05-05T18:18:15.363462Z","end":"2026-05-05T18:18:15.935199Z","steps":["trace[995035478] 'read index received' (duration: 564.591666ms)","trace[995035478] 'applied index is now lower than readState.Index' (duration: 7.144839ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:18:15.935513Z","caller":"traceutil/trace.go:171","msg":"trace[939342769] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"450.933146ms","start":"2026-05-05T18:18:15.484567Z","end":"2026-05-05T18:18:15.9355Z","steps":["trace[939342769] 'process raft request' (duration: 450.503354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:15.935836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:15.484543Z","time spent":"450.998728ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:18:15.935969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.513165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:18:15.936004Z","caller":"traceutil/trace.go:171","msg":"trace[468206189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"572.601779ms","start":"2026-05-05T18:18:15.363394Z","end":"2026-05-05T18:18:15.935996Z","steps":["trace[468206189] 'agreement among raft nodes before linearized reading' (duration: 572.554828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:15.936023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:15.363372Z","time spent":"572.64689ms","remote":"127.0.0.1:36476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:18:15.936114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.373183ms","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-05-05T18:18:15.936131Z","caller":"traceutil/trace.go:171","msg":"trace[574892882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"555.412644ms","start":"2026-05-05T18:18:15.380713Z","end":"2026-05-05T18:18:15.936126Z","steps":["trace[574892882] 'agreement among raft nodes before linearized reading' (duration: 555.379632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:15.936153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:15.380699Z","time spent":"555.450595ms","remote":"127.0.0.1:36730","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-05-05T18:18:15.937052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.698146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:18:15.93723Z","caller":"traceutil/trace.go:171","msg":"trace[2121205142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"548.840759ms","start":"2026-05-05T18:18:15.388328Z","end":"2026-05-05T18:18:15.937168Z","steps":["trace[2121205142] 'agreement among raft nodes before linearized reading' (duration: 548.572043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:15.937327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:15.388319Z","time spent":"548.994423ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T18:18:15.936637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.328847ms","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-05-05T18:18:15.937564Z","caller":"traceutil/trace.go:171","msg":"trace[1329556734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"537.311304ms","start":"2026-05-05T18:18:15.400236Z","end":"2026-05-05T18:18:15.937548Z","steps":["trace[1329556734] 'agreement among raft nodes before linearized reading' (duration: 536.234155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:15.937714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:15.400211Z","time spent":"537.483519ms","remote":"127.0.0.1:36730","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-05-05T18:18:15.937958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.402015ms","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-05-05T18:18:15.938031Z","caller":"traceutil/trace.go:171","msg":"trace[271996385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"526.534798ms","start":"2026-05-05T18:18:15.411488Z","end":"2026-05-05T18:18:15.938022Z","steps":["trace[271996385] 'agreement among raft nodes before linearized reading' (duration: 526.365804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:15.938059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:15.411468Z","time spent":"526.58421ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T18:18:16.648877Z","caller":"traceutil/trace.go:171","msg":"trace[385609703] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"654.164751ms","start":"2026-05-05T18:18:15.994694Z","end":"2026-05-05T18:18:16.648859Z","steps":["trace[385609703] 'process raft request' (duration: 605.351507ms)","trace[385609703] 'compare' (duration: 48.509175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:18:16.649061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:15.994629Z","time spent":"654.383686ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:18:16.648827Z","caller":"traceutil/trace.go:171","msg":"trace[760832573] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"490.760321ms","start":"2026-05-05T18:18:16.158046Z","end":"2026-05-05T18:18:16.648806Z","steps":["trace[760832573] 'read index received' (duration: 442.02379ms)","trace[760832573] 'applied index is now lower than readState.Index' (duration: 48.735511ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:18:16.649881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.33417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:18:16.649881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.818949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T18:18:16.649936Z","caller":"traceutil/trace.go:171","msg":"trace[1618376997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"285.419311ms","start":"2026-05-05T18:18:16.364503Z","end":"2026-05-05T18:18:16.649922Z","steps":["trace[1618376997] 'agreement among raft nodes before linearized reading' (duration: 285.297488ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:16.649957Z","caller":"traceutil/trace.go:171","msg":"trace[134782400] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:626; }","duration":"491.902331ms","start":"2026-05-05T18:18:16.158034Z","end":"2026-05-05T18:18:16.649937Z","steps":["trace[134782400] 'agreement among raft nodes before linearized reading' (duration: 491.10859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:16.650013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:16.157989Z","time spent":"492.012104ms","remote":"127.0.0.1:36516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-05-05T18:18:17.669211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.402565ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-05T18:18:17.66932Z","caller":"traceutil/trace.go:171","msg":"trace[1106056369] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"1.016577101s","start":"2026-05-05T18:18:16.652722Z","end":"2026-05-05T18:18:17.669299Z","steps":["trace[1106056369] 'read index received' (duration: 396.008532ms)","trace[1106056369] 'applied index is now lower than readState.Index' (duration: 620.566899ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:18:17.669425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016686674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-05T18:18:17.669464Z","caller":"traceutil/trace.go:171","msg":"trace[1005553461] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:626; }","duration":"1.016755145s","start":"2026-05-05T18:18:16.652696Z","end":"2026-05-05T18:18:17.669451Z","steps":["trace[1005553461] 'agreement among raft nodes before linearized reading' (duration: 1.016659283s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:17.669507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:16.652685Z","time spent":"1.016809777s","remote":"127.0.0.1:36656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-05T18:18:17.669534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:16.651292Z","time spent":"1.018238874s","remote":"127.0.0.1:36516","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T18:18:17.805699Z","caller":"traceutil/trace.go:171","msg":"trace[1830728885] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"124.841847ms","start":"2026-05-05T18:18:17.680829Z","end":"2026-05-05T18:18:17.80567Z","steps":["trace[1830728885] 'process raft request' (duration: 124.589421ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:18.092682Z","caller":"traceutil/trace.go:171","msg":"trace[1994443112] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"105.880925ms","start":"2026-05-05T18:18:17.986245Z","end":"2026-05-05T18:18:18.092126Z","steps":["trace[1994443112] 'process raft request' (duration: 57.026531ms)","trace[1994443112] 'compare' (duration: 48.621228ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:18:18.092849Z","caller":"traceutil/trace.go:171","msg":"trace[1097481141] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"106.218974ms","start":"2026-05-05T18:18:17.986596Z","end":"2026-05-05T18:18:18.092815Z","steps":["trace[1097481141] 'process raft request' (duration: 105.433584ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:19.132045Z","caller":"traceutil/trace.go:171","msg":"trace[2004571689] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"125.7357ms","start":"2026-05-05T18:18:19.006291Z","end":"2026-05-05T18:18:19.132027Z","steps":["trace[2004571689] 'process raft request' (duration: 125.622808ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:19.326905Z","caller":"traceutil/trace.go:171","msg":"trace[964310398] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"308.292428ms","start":"2026-05-05T18:18:19.018589Z","end":"2026-05-05T18:18:19.326881Z","steps":["trace[964310398] 'process raft request' (duration: 216.750913ms)","trace[964310398] 'compare' (duration: 91.421112ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:18:19.327035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:19.018561Z","time spent":"308.40157ms","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:18:22.354608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.37132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:18:22.354732Z","caller":"traceutil/trace.go:171","msg":"trace[1172926732] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"240.021569ms","start":"2026-05-05T18:18:22.114692Z","end":"2026-05-05T18:18:22.354713Z","steps":["trace[1172926732] 'process raft request' (duration: 48.462274ms)","trace[1172926732] 'compare' (duration: 191.224265ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:18:22.358166Z","caller":"traceutil/trace.go:171","msg":"trace[1414307841] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"132.920062ms","start":"2026-05-05T18:18:22.225223Z","end":"2026-05-05T18:18:22.358143Z","steps":["trace[1414307841] 'process raft request' (duration: 132.734116ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:28.207964Z","caller":"traceutil/trace.go:171","msg":"trace[257475847] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"164.956149ms","start":"2026-05-05T18:18:28.04299Z","end":"2026-05-05T18:18:28.207946Z","steps":["trace[257475847] 'read index received' (duration: 164.793265ms)","trace[257475847] 'applied index is now lower than readState.Index' (duration: 162.244µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:18:28.208196Z","caller":"traceutil/trace.go:171","msg":"trace[955241044] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"176.942287ms","start":"2026-05-05T18:18:28.031244Z","end":"2026-05-05T18:18:28.208187Z","steps":["trace[955241044] 'process raft request' (duration: 176.597028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:28.208395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.398371ms","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-05-05T18:18:28.208423Z","caller":"traceutil/trace.go:171","msg":"trace[891325938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"165.470403ms","start":"2026-05-05T18:18:28.042946Z","end":"2026-05-05T18:18:28.208417Z","steps":["trace[891325938] 'agreement among raft nodes before linearized reading' (duration: 165.375351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:33.801576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.526264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:18:33.801679Z","caller":"traceutil/trace.go:171","msg":"trace[685585479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"436.72519ms","start":"2026-05-05T18:18:33.364936Z","end":"2026-05-05T18:18:33.801661Z","steps":["trace[685585479] 'range keys from in-memory index tree' (duration: 436.36345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:33.801739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:33.364913Z","time spent":"436.814882ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:18:33.801772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.596812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9rkrw\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-05-05T18:18:33.80191Z","caller":"traceutil/trace.go:171","msg":"trace[332364664] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9rkrw; range_end:; response_count:1; response_revision:783; }","duration":"226.742277ms","start":"2026-05-05T18:18:33.575105Z","end":"2026-05-05T18:18:33.801847Z","steps":["trace[332364664] 'range keys from in-memory index tree' (duration: 226.399057ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:34.37782Z","caller":"traceutil/trace.go:171","msg":"trace[1555631477] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"560.624692ms","start":"2026-05-05T18:18:33.817174Z","end":"2026-05-05T18:18:34.377798Z","steps":["trace[1555631477] 'process raft request' (duration: 560.425657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:34.377981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:33.817135Z","time spent":"560.757275ms","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:18:34.378258Z","caller":"traceutil/trace.go:171","msg":"trace[1296633048] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:815; }","duration":"320.005638ms","start":"2026-05-05T18:18:34.058218Z","end":"2026-05-05T18:18:34.378224Z","steps":["trace[1296633048] 'read index received' (duration: 319.975527ms)","trace[1296633048] 'applied index is now lower than readState.Index' (duration: 28.32µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:18:34.378569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.306915ms","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-05-05T18:18:34.37866Z","caller":"traceutil/trace.go:171","msg":"trace[261058642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"320.442528ms","start":"2026-05-05T18:18:34.058166Z","end":"2026-05-05T18:18:34.378608Z","steps":["trace[261058642] 'agreement among raft nodes before linearized reading' (duration: 320.192012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:34.378705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:34.058145Z","time spent":"320.549132ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T18:18:34.556531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.184874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:18:34.556625Z","caller":"traceutil/trace.go:171","msg":"trace[2040537104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"191.324609ms","start":"2026-05-05T18:18:34.365279Z","end":"2026-05-05T18:18:34.556604Z","steps":["trace[2040537104] 'agreement among raft nodes before linearized reading' (duration: 191.085382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:34.556703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.298663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:18:34.556335Z","caller":"traceutil/trace.go:171","msg":"trace[1312865068] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"177.967325ms","start":"2026-05-05T18:18:34.378341Z","end":"2026-05-05T18:18:34.556308Z","steps":["trace[1312865068] 'read index received' (duration: 177.510883ms)","trace[1312865068] 'applied index is now lower than readState.Index' (duration: 455.392µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:18:34.556946Z","caller":"traceutil/trace.go:171","msg":"trace[126126144] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"445.554274ms","start":"2026-05-05T18:18:34.111343Z","end":"2026-05-05T18:18:34.556897Z","steps":["trace[126126144] 'process raft request' (duration: 444.67288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:34.55679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.420266ms","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-05-05T18:18:34.55684Z","caller":"traceutil/trace.go:171","msg":"trace[1160295780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"241.481427ms","start":"2026-05-05T18:18:34.315345Z","end":"2026-05-05T18:18:34.556827Z","steps":["trace[1160295780] 'agreement among raft nodes before linearized reading' (duration: 241.103456ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:34.557068Z","caller":"traceutil/trace.go:171","msg":"trace[1746148195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"241.751464ms","start":"2026-05-05T18:18:34.315292Z","end":"2026-05-05T18:18:34.557044Z","steps":["trace[1746148195] 'agreement among raft nodes before linearized reading' (duration: 241.269872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:34.557124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:34.111302Z","time spent":"445.736268ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:18:34.557513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.417446ms","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-05-05T18:18:34.557582Z","caller":"traceutil/trace.go:171","msg":"trace[2115673784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"275.521119ms","start":"2026-05-05T18:18:34.282045Z","end":"2026-05-05T18:18:34.557566Z","steps":["trace[2115673784] 'agreement among raft nodes before linearized reading' (duration: 275.242272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:34.836899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.750879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:18:34.837216Z","caller":"traceutil/trace.go:171","msg":"trace[1159258830] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"453.849543ms","start":"2026-05-05T18:18:34.383345Z","end":"2026-05-05T18:18:34.837194Z","steps":["trace[1159258830] 'process raft request' (duration: 292.722514ms)","trace[1159258830] 'compare' (duration: 160.656717ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:18:34.837242Z","caller":"traceutil/trace.go:171","msg":"trace[2140351596] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"446.043587ms","start":"2026-05-05T18:18:34.391185Z","end":"2026-05-05T18:18:34.837228Z","steps":["trace[2140351596] 'process raft request' (duration: 445.966465ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:34.837254Z","caller":"traceutil/trace.go:171","msg":"trace[559764244] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"447.869954ms","start":"2026-05-05T18:18:34.389357Z","end":"2026-05-05T18:18:34.837227Z","steps":["trace[559764244] 'process raft request' (duration: 447.71744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:34.837313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:34.391167Z","time spent":"446.108339ms","remote":"127.0.0.1:36904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:18:34.837401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:34.389335Z","time spent":"447.989078ms","remote":"127.0.0.1:36636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:18:34.837357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:34.383315Z","time spent":"453.951127ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:18:34.84451Z","caller":"traceutil/trace.go:171","msg":"trace[1844120543] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"281.876347ms","start":"2026-05-05T18:18:34.562606Z","end":"2026-05-05T18:18:34.844483Z","steps":["trace[1844120543] 'process raft request' (duration: 281.62291ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:34.844497Z","caller":"traceutil/trace.go:171","msg":"trace[213945356] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"281.781395ms","start":"2026-05-05T18:18:34.562697Z","end":"2026-05-05T18:18:34.844479Z","steps":["trace[213945356] 'process raft request' (duration: 281.679493ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:34.844763Z","caller":"traceutil/trace.go:171","msg":"trace[1530854967] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"281.9788ms","start":"2026-05-05T18:18:34.56277Z","end":"2026-05-05T18:18:34.844749Z","steps":["trace[1530854967] 'process raft request' (duration: 281.675202ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:37.432251Z","caller":"traceutil/trace.go:171","msg":"trace[1137859290] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"231.927963ms","start":"2026-05-05T18:18:37.200289Z","end":"2026-05-05T18:18:37.432217Z","steps":["trace[1137859290] 'process raft request' (duration: 231.743239ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:41.628409Z","caller":"traceutil/trace.go:171","msg":"trace[1431797083] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"105.476704ms","start":"2026-05-05T18:18:41.522909Z","end":"2026-05-05T18:18:41.628386Z","steps":["trace[1431797083] 'process raft request' (duration: 105.3101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:46.60629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.785504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:18:46.60665Z","caller":"traceutil/trace.go:171","msg":"trace[1759808831] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"385.973265ms","start":"2026-05-05T18:18:46.220649Z","end":"2026-05-05T18:18:46.606622Z","steps":["trace[1759808831] 'process raft request' (duration: 117.772621ms)","trace[1759808831] 'compare' (duration: 267.628459ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:18:46.606708Z","caller":"traceutil/trace.go:171","msg":"trace[480355781] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:947; }","duration":"242.138604ms","start":"2026-05-05T18:18:46.364551Z","end":"2026-05-05T18:18:46.606689Z","steps":["trace[480355781] 'read index received' (duration: 241.784574ms)","trace[480355781] 'applied index is now lower than readState.Index' (duration: 353.24µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:18:46.606848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:18:46.220628Z","time spent":"386.13279ms","remote":"127.0.0.1:36516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:18:46.606878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.32002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:18:46.606907Z","caller":"traceutil/trace.go:171","msg":"trace[1795212493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"242.38381ms","start":"2026-05-05T18:18:46.364514Z","end":"2026-05-05T18:18:46.606898Z","steps":["trace[1795212493] 'agreement among raft nodes before linearized reading' (duration: 242.271318ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:46.607182Z","caller":"traceutil/trace.go:171","msg":"trace[1152991657] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"255.604311ms","start":"2026-05-05T18:18:46.351564Z","end":"2026-05-05T18:18:46.607168Z","steps":["trace[1152991657] 'process raft request' (duration: 255.022227ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:49.352624Z","caller":"traceutil/trace.go:171","msg":"trace[806176334] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"154.911934ms","start":"2026-05-05T18:18:49.197697Z","end":"2026-05-05T18:18:49.352609Z","steps":["trace[806176334] 'process raft request' (duration: 154.816171ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:49.367009Z","caller":"traceutil/trace.go:171","msg":"trace[1806018520] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"149.758996ms","start":"2026-05-05T18:18:49.217225Z","end":"2026-05-05T18:18:49.366984Z","steps":["trace[1806018520] 'read index received' (duration: 135.379386ms)","trace[1806018520] 'applied index is now lower than readState.Index' (duration: 14.37865ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:18:49.367301Z","caller":"traceutil/trace.go:171","msg":"trace[741892251] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"169.052989ms","start":"2026-05-05T18:18:49.198226Z","end":"2026-05-05T18:18:49.367279Z","steps":["trace[741892251] 'process raft request' (duration: 168.631138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:49.367351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.550533ms","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":"warn","ts":"2026-05-05T18:18:49.367366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.118147ms","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-05-05T18:18:49.367394Z","caller":"traceutil/trace.go:171","msg":"trace[45942850] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"149.627554ms","start":"2026-05-05T18:18:49.217756Z","end":"2026-05-05T18:18:49.367384Z","steps":["trace[45942850] 'agreement among raft nodes before linearized reading' (duration: 149.499911ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:49.367407Z","caller":"traceutil/trace.go:171","msg":"trace[1599023727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"150.21235ms","start":"2026-05-05T18:18:49.217182Z","end":"2026-05-05T18:18:49.367395Z","steps":["trace[1599023727] 'agreement among raft nodes before linearized reading' (duration: 150.046716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:18:51.399059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.747431ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T18:18:51.588741Z","caller":"traceutil/trace.go:171","msg":"trace[1923275324] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"184.454187ms","start":"2026-05-05T18:18:51.404242Z","end":"2026-05-05T18:18:51.588696Z","steps":["trace[1923275324] 'process raft request' (duration: 183.150982ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:18:51.588905Z","caller":"traceutil/trace.go:171","msg":"trace[1023369727] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"183.731508ms","start":"2026-05-05T18:18:51.405146Z","end":"2026-05-05T18:18:51.588877Z","steps":["trace[1023369727] 'process raft request' (duration: 183.512462ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:19:05.99587Z","caller":"traceutil/trace.go:171","msg":"trace[2110659778] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"141.822088ms","start":"2026-05-05T18:19:05.854028Z","end":"2026-05-05T18:19:05.995851Z","steps":["trace[2110659778] 'process raft request' (duration: 141.676644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:09.842006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741890235991405,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T18:19:09.913668Z","caller":"traceutil/trace.go:171","msg":"trace[618180380] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"572.720395ms","start":"2026-05-05T18:19:09.34093Z","end":"2026-05-05T18:19:09.91365Z","steps":["trace[618180380] 'read index received' (duration: 572.625643ms)","trace[618180380] 'applied index is now lower than readState.Index' (duration: 93.922µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:19:09.913709Z","caller":"traceutil/trace.go:171","msg":"trace[1922042780] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"694.066841ms","start":"2026-05-05T18:19:09.219612Z","end":"2026-05-05T18:19:09.913678Z","steps":["trace[1922042780] 'process raft request' (duration: 693.936588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:09.913874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:09.219589Z","time spent":"694.196456ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:19:09.913912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.8886ms","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-05-05T18:19:09.913947Z","caller":"traceutil/trace.go:171","msg":"trace[1741130580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"285.962522ms","start":"2026-05-05T18:19:09.627977Z","end":"2026-05-05T18:19:09.91394Z","steps":["trace[1741130580] 'agreement among raft nodes before linearized reading' (duration: 285.842709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:09.913901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.965012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T18:19:09.91398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.156878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:19:09.914019Z","caller":"traceutil/trace.go:171","msg":"trace[839228154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"122.214398ms","start":"2026-05-05T18:19:09.791794Z","end":"2026-05-05T18:19:09.914009Z","steps":["trace[839228154] 'agreement among raft nodes before linearized reading' (duration: 122.136296ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:19:09.91404Z","caller":"traceutil/trace.go:171","msg":"trace[182953706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"573.133076ms","start":"2026-05-05T18:19:09.340877Z","end":"2026-05-05T18:19:09.91401Z","steps":["trace[182953706] 'agreement among raft nodes before linearized reading' (duration: 572.905891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:09.914149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:09.340857Z","time spent":"573.25141ms","remote":"127.0.0.1:36730","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-05-05T18:19:10.733035Z","caller":"traceutil/trace.go:171","msg":"trace[2015979963] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"811.532942ms","start":"2026-05-05T18:19:09.921439Z","end":"2026-05-05T18:19:10.732971Z","steps":["trace[2015979963] 'process raft request' (duration: 811.337497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:10.733198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:09.921413Z","time spent":"811.699887ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:19:10.733331Z","caller":"traceutil/trace.go:171","msg":"trace[247886489] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"811.714918ms","start":"2026-05-05T18:19:09.921589Z","end":"2026-05-05T18:19:10.733304Z","steps":["trace[247886489] 'process raft request' (duration: 811.615384ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:19:10.733348Z","caller":"traceutil/trace.go:171","msg":"trace[897629021] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"811.80564ms","start":"2026-05-05T18:19:09.921511Z","end":"2026-05-05T18:19:10.733317Z","steps":["trace[897629021] 'process raft request' (duration: 811.408169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:10.733473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:09.92157Z","time spent":"811.824981ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:19:10.733509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:09.921505Z","time spent":"811.910223ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:19:10.745552Z","caller":"traceutil/trace.go:171","msg":"trace[169829573] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1041; }","duration":"375.260448ms","start":"2026-05-05T18:19:10.37028Z","end":"2026-05-05T18:19:10.745541Z","steps":["trace[169829573] 'read index received' (duration: 362.576987ms)","trace[169829573] 'applied index is now lower than readState.Index' (duration: 12.682781ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:19:10.745644Z","caller":"traceutil/trace.go:171","msg":"trace[570714490] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"497.036825ms","start":"2026-05-05T18:19:10.248601Z","end":"2026-05-05T18:19:10.745637Z","steps":["trace[570714490] 'process raft request' (duration: 496.913562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:10.745699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:10.248574Z","time spent":"497.096377ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:19:10.745656Z","caller":"traceutil/trace.go:171","msg":"trace[1996108114] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"738.456233ms","start":"2026-05-05T18:19:10.007157Z","end":"2026-05-05T18:19:10.745614Z","steps":["trace[1996108114] 'process raft request' (duration: 738.279968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:10.745857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.577327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:19:10.745887Z","caller":"traceutil/trace.go:171","msg":"trace[1314527237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"375.644629ms","start":"2026-05-05T18:19:10.370234Z","end":"2026-05-05T18:19:10.745879Z","steps":["trace[1314527237] 'agreement among raft nodes before linearized reading' (duration: 375.582947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:10.745907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:10.370211Z","time spent":"375.69223ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:19:10.745899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:10.007136Z","time spent":"738.634017ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:19:34.387055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.547995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:19:34.387543Z","caller":"traceutil/trace.go:171","msg":"trace[1797938688] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"273.077876ms","start":"2026-05-05T18:19:34.114435Z","end":"2026-05-05T18:19:34.387513Z","steps":["trace[1797938688] 'process raft request' (duration: 47.956966ms)","trace[1797938688] 'compare' (duration: 223.976089ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:19:35.221361Z","caller":"traceutil/trace.go:171","msg":"trace[1340528879] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"326.302015ms","start":"2026-05-05T18:19:34.895036Z","end":"2026-05-05T18:19:35.221338Z","steps":["trace[1340528879] 'read index received' (duration: 326.11613ms)","trace[1340528879] 'applied index is now lower than readState.Index' (duration: 184.975µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:19:35.221583Z","caller":"traceutil/trace.go:171","msg":"trace[68412890] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"336.748755ms","start":"2026-05-05T18:19:34.884718Z","end":"2026-05-05T18:19:35.221466Z","steps":["trace[68412890] 'process raft request' (duration: 336.488498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:35.221761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:34.884692Z","time spent":"336.979731ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:19:35.221821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.499229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:19:35.221913Z","caller":"traceutil/trace.go:171","msg":"trace[515275162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"222.650504ms","start":"2026-05-05T18:19:34.999242Z","end":"2026-05-05T18:19:35.221892Z","steps":["trace[515275162] 'agreement among raft nodes before linearized reading' (duration: 222.387496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:35.221938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.343876ms","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-05-05T18:19:35.221991Z","caller":"traceutil/trace.go:171","msg":"trace[896858918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"222.474559ms","start":"2026-05-05T18:19:34.999498Z","end":"2026-05-05T18:19:35.221972Z","steps":["trace[896858918] 'agreement among raft nodes before linearized reading' (duration: 222.17483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:35.22185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.719505ms","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-05-05T18:19:35.222082Z","caller":"traceutil/trace.go:171","msg":"trace[631294034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1082; }","duration":"327.055325ms","start":"2026-05-05T18:19:34.895011Z","end":"2026-05-05T18:19:35.222067Z","steps":["trace[631294034] 'agreement among raft nodes before linearized reading' (duration: 326.576232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:35.222195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:34.895001Z","time spent":"327.153497ms","remote":"127.0.0.1:36730","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-05-05T18:19:36.231196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741890235991618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T18:19:36.731745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741890235991618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T18:19:36.847226Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.620856686s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T18:19:36.847812Z","caller":"traceutil/trace.go:171","msg":"trace[1649495578] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"1.621544775s","start":"2026-05-05T18:19:35.226242Z","end":"2026-05-05T18:19:36.847786Z","steps":["trace[1649495578] 'process raft request' (duration: 1.621493793s)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:19:36.847879Z","caller":"traceutil/trace.go:171","msg":"trace[1472830138] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"1.621644057s","start":"2026-05-05T18:19:35.226203Z","end":"2026-05-05T18:19:36.847847Z","steps":["trace[1472830138] 'process raft request' (duration: 1.621323269s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:36.848181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:35.22622Z","time spent":"1.621825101s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:19:36.848242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:35.226171Z","time spent":"1.621863573s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:19:36.854686Z","caller":"traceutil/trace.go:171","msg":"trace[102546674] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1126; }","duration":"1.124225142s","start":"2026-05-05T18:19:35.730431Z","end":"2026-05-05T18:19:36.854656Z","steps":["trace[102546674] 'read index received' (duration: 1.117121582s)","trace[102546674] 'applied index is now lower than readState.Index' (duration: 7.09334ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:19:36.854847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124407087s","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-05-05T18:19:36.854914Z","caller":"traceutil/trace.go:171","msg":"trace[1252599170] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1085; }","duration":"1.12452734s","start":"2026-05-05T18:19:35.730373Z","end":"2026-05-05T18:19:36.854901Z","steps":["trace[1252599170] 'agreement among raft nodes before linearized reading' (duration: 1.124420538s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:36.854953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:35.730347Z","time spent":"1.124597621s","remote":"127.0.0.1:36626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-05T18:19:36.854807Z","caller":"traceutil/trace.go:171","msg":"trace[1013520163] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"1.626725064s","start":"2026-05-05T18:19:35.22807Z","end":"2026-05-05T18:19:36.854795Z","steps":["trace[1013520163] 'process raft request' (duration: 1.626507908s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:36.855072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:35.22804Z","time spent":"1.62695568s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:19:36.855229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.105615ms","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-05-05T18:19:36.855255Z","caller":"traceutil/trace.go:171","msg":"trace[1042089842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1085; }","duration":"463.179697ms","start":"2026-05-05T18:19:36.392069Z","end":"2026-05-05T18:19:36.855249Z","steps":["trace[1042089842] 'agreement among raft nodes before linearized reading' (duration: 463.093735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:36.855276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:36.392044Z","time spent":"463.227568ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T18:19:36.855219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.917394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:19:36.855343Z","caller":"traceutil/trace.go:171","msg":"trace[1161497813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1085; }","duration":"490.087388ms","start":"2026-05-05T18:19:36.365245Z","end":"2026-05-05T18:19:36.855332Z","steps":["trace[1161497813] 'agreement among raft nodes before linearized reading' (duration: 489.932964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:36.85541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:36.365223Z","time spent":"490.178451ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:19:36.855456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.730382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T18:19:36.855481Z","caller":"traceutil/trace.go:171","msg":"trace[269484465] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1085; }","duration":"693.788434ms","start":"2026-05-05T18:19:36.161686Z","end":"2026-05-05T18:19:36.855475Z","steps":["trace[269484465] 'agreement among raft nodes before linearized reading' (duration: 693.727522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:36.855498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:36.161665Z","time spent":"693.829074ms","remote":"127.0.0.1:36516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-05-05T18:19:36.855743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.005307ms","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-05-05T18:19:36.855845Z","caller":"traceutil/trace.go:171","msg":"trace[2003178321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1085; }","duration":"808.130741ms","start":"2026-05-05T18:19:36.04769Z","end":"2026-05-05T18:19:36.855821Z","steps":["trace[2003178321] 'agreement among raft nodes before linearized reading' (duration: 807.379011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:36.855954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:36.047669Z","time spent":"808.253075ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T18:19:38.434801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.74787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T18:19:38.434884Z","caller":"traceutil/trace.go:171","msg":"trace[1255684915] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1090; }","duration":"287.828042ms","start":"2026-05-05T18:19:38.14704Z","end":"2026-05-05T18:19:38.434868Z","steps":["trace[1255684915] 'count revisions from in-memory index tree' (duration: 287.662297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:38.43465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.793486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:19:38.434981Z","caller":"traceutil/trace.go:171","msg":"trace[83791152] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1090; }","duration":"646.180336ms","start":"2026-05-05T18:19:37.78879Z","end":"2026-05-05T18:19:38.43497Z","steps":["trace[83791152] 'count revisions from in-memory index tree' (duration: 645.697694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:38.435012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:37.788769Z","time spent":"646.231248ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-05-05T18:19:39.677047Z","caller":"traceutil/trace.go:171","msg":"trace[1497229835] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"300.383299ms","start":"2026-05-05T18:19:39.376645Z","end":"2026-05-05T18:19:39.677029Z","steps":["trace[1497229835] 'read index received' (duration: 300.213345ms)","trace[1497229835] 'applied index is now lower than readState.Index' (duration: 169.024µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:19:39.677145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.478302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T18:19:39.677168Z","caller":"traceutil/trace.go:171","msg":"trace[73750950] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1096; }","duration":"300.555654ms","start":"2026-05-05T18:19:39.376605Z","end":"2026-05-05T18:19:39.67716Z","steps":["trace[73750950] 'agreement among raft nodes before linearized reading' (duration: 300.494332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:39.677203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:39.376582Z","time spent":"300.614475ms","remote":"127.0.0.1:36726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-05-05T18:19:39.677349Z","caller":"traceutil/trace.go:171","msg":"trace[269795238] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"440.647053ms","start":"2026-05-05T18:19:39.236676Z","end":"2026-05-05T18:19:39.677323Z","steps":["trace[269795238] 'process raft request' (duration: 440.203731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:39.677492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:39.236658Z","time spent":"440.759345ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:19:52.866683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741890235991849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T18:19:54.052288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18269475s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T18:19:54.052387Z","caller":"traceutil/trace.go:171","msg":"trace[1077714714] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"1.686556129s","start":"2026-05-05T18:19:52.365812Z","end":"2026-05-05T18:19:54.052368Z","steps":["trace[1077714714] 'read index received' (duration: 503.716604ms)","trace[1077714714] 'applied index is now lower than readState.Index' (duration: 1.182838235s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:19:54.05249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686673312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:19:54.052516Z","caller":"traceutil/trace.go:171","msg":"trace[1378929299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"1.686753445s","start":"2026-05-05T18:19:52.365753Z","end":"2026-05-05T18:19:54.052507Z","steps":["trace[1378929299] 'agreement among raft nodes before linearized reading' (duration: 1.686695243s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:54.052545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:52.365734Z","time spent":"1.686804567s","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T18:19:54.077595Z","caller":"traceutil/trace.go:171","msg":"trace[1734550255] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"359.381552ms","start":"2026-05-05T18:19:53.718182Z","end":"2026-05-05T18:19:54.077564Z","steps":["trace[1734550255] 'process raft request' (duration: 359.122694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:54.077691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091673449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T18:19:54.077744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089558641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T18:19:54.077747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:53.718162Z","time spent":"359.507476ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:19:54.077814Z","caller":"traceutil/trace.go:171","msg":"trace[2058321139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"1.089606702s","start":"2026-05-05T18:19:52.988169Z","end":"2026-05-05T18:19:54.077775Z","steps":["trace[2058321139] 'agreement among raft nodes before linearized reading' (duration: 1.089436828s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:54.077838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:52.988151Z","time spent":"1.089682585s","remote":"127.0.0.1:36730","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-05-05T18:19:54.077856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090118216s","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-05-05T18:19:54.077878Z","caller":"traceutil/trace.go:171","msg":"trace[932716554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"1.090141317s","start":"2026-05-05T18:19:52.987731Z","end":"2026-05-05T18:19:54.077872Z","steps":["trace[932716554] 'agreement among raft nodes before linearized reading' (duration: 1.090039624s)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:19:54.077749Z","caller":"traceutil/trace.go:171","msg":"trace[1051503889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"1.091794362s","start":"2026-05-05T18:19:52.98594Z","end":"2026-05-05T18:19:54.077735Z","steps":["trace[1051503889] 'agreement among raft nodes before linearized reading' (duration: 1.091579057s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:54.077875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099364494s","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-05-05T18:19:54.077956Z","caller":"traceutil/trace.go:171","msg":"trace[1926601969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"1.099483968s","start":"2026-05-05T18:19:52.978455Z","end":"2026-05-05T18:19:54.077939Z","steps":["trace[1926601969] 'agreement among raft nodes before linearized reading' (duration: 1.099262422s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:54.077991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:52.978439Z","time spent":"1.099545089s","remote":"127.0.0.1:36730","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-05-05T18:19:54.077904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:52.987678Z","time spent":"1.090220429s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T18:19:54.078012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093890818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T18:19:54.077907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:52.985917Z","time spent":"1.091984347s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T18:19:54.078083Z","caller":"traceutil/trace.go:171","msg":"trace[1520831587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"1.09397387s","start":"2026-05-05T18:19:52.984088Z","end":"2026-05-05T18:19:54.078062Z","steps":["trace[1520831587] 'agreement among raft nodes before linearized reading' (duration: 1.093428226s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:19:54.078117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:19:52.984082Z","time spent":"1.094026541s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T18:20:10.489899Z","caller":"traceutil/trace.go:171","msg":"trace[1734459155] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"122.040934ms","start":"2026-05-05T18:20:10.367841Z","end":"2026-05-05T18:20:10.489881Z","steps":["trace[1734459155] 'process raft request' (duration: 121.720385ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:12.062207Z","caller":"traceutil/trace.go:171","msg":"trace[1833370966] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"118.035403ms","start":"2026-05-05T18:20:11.944155Z","end":"2026-05-05T18:20:12.06219Z","steps":["trace[1833370966] 'process raft request' (duration: 117.89428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:15.590091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.552937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:20:15.590315Z","caller":"traceutil/trace.go:171","msg":"trace[25331529] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1350; }","duration":"753.386916ms","start":"2026-05-05T18:20:14.836909Z","end":"2026-05-05T18:20:15.590295Z","steps":["trace[25331529] 'read index received' (duration: 402.55877ms)","trace[25331529] 'applied index is now lower than readState.Index' (duration: 350.827216ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:15.590412Z","caller":"traceutil/trace.go:171","msg":"trace[89401838] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"820.304321ms","start":"2026-05-05T18:20:14.770095Z","end":"2026-05-05T18:20:15.5904Z","steps":["trace[89401838] 'process raft request' (duration: 469.358463ms)","trace[89401838] 'compare' (duration: 350.329872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:20:15.590479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:14.770073Z","time spent":"820.372683ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:20:15.590551Z","caller":"traceutil/trace.go:171","msg":"trace[684497684] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"813.579554ms","start":"2026-05-05T18:20:14.77695Z","end":"2026-05-05T18:20:15.590529Z","steps":["trace[684497684] 'process raft request' (duration: 813.263526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:15.590604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.683513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T18:20:15.590598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.202232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:20:15.590657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.51037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:20:15.590698Z","caller":"traceutil/trace.go:171","msg":"trace[1079966879] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1298; }","duration":"349.357347ms","start":"2026-05-05T18:20:15.241327Z","end":"2026-05-05T18:20:15.590685Z","steps":["trace[1079966879] 'agreement among raft nodes before linearized reading' (duration: 349.192422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:15.590745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:15.241308Z","time spent":"349.42801ms","remote":"127.0.0.1:60158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-05-05T18:20:15.590743Z","caller":"traceutil/trace.go:171","msg":"trace[397147232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"225.557761ms","start":"2026-05-05T18:20:15.365133Z","end":"2026-05-05T18:20:15.59069Z","steps":["trace[397147232] 'agreement among raft nodes before linearized reading' (duration: 225.483489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:15.590648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:14.776935Z","time spent":"813.665215ms","remote":"127.0.0.1:36562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:20:15.590642Z","caller":"traceutil/trace.go:171","msg":"trace[1010105325] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1298; }","duration":"753.770775ms","start":"2026-05-05T18:20:14.836859Z","end":"2026-05-05T18:20:15.59063Z","steps":["trace[1010105325] 'agreement among raft nodes before linearized reading' (duration: 753.643602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:15.590961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:14.836771Z","time spent":"754.179256ms","remote":"127.0.0.1:36932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-05T18:20:15.953822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.81033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:20:15.953945Z","caller":"traceutil/trace.go:171","msg":"trace[671888283] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"358.98234ms","start":"2026-05-05T18:20:15.59495Z","end":"2026-05-05T18:20:15.953932Z","steps":["trace[671888283] 'read index received' (duration: 134.014385ms)","trace[671888283] 'applied index is now lower than readState.Index' (duration: 224.967115ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:20:15.954079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.113814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-05T18:20:15.954105Z","caller":"traceutil/trace.go:171","msg":"trace[337017663] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1299; }","duration":"359.191447ms","start":"2026-05-05T18:20:15.594906Z","end":"2026-05-05T18:20:15.954098Z","steps":["trace[337017663] 'agreement among raft nodes before linearized reading' (duration: 359.059863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:15.95413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:15.594888Z","time spent":"359.236578ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T18:20:15.954164Z","caller":"traceutil/trace.go:171","msg":"trace[1790031882] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"359.808842ms","start":"2026-05-05T18:20:15.59433Z","end":"2026-05-05T18:20:15.954139Z","steps":["trace[1790031882] 'process raft request' (duration: 134.624021ms)","trace[1790031882] 'compare' (duration: 224.592035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:20:15.954673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:15.594313Z","time spent":"359.911375ms","remote":"127.0.0.1:36562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:20:15.96748Z","caller":"traceutil/trace.go:171","msg":"trace[1563571627] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"369.259542ms","start":"2026-05-05T18:20:15.598197Z","end":"2026-05-05T18:20:15.967457Z","steps":["trace[1563571627] 'process raft request' (duration: 369.1629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:15.967588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:15.598179Z","time spent":"369.359954ms","remote":"127.0.0.1:36562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:20:16.941778Z","caller":"traceutil/trace.go:171","msg":"trace[1849359324] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"350.483026ms","start":"2026-05-05T18:20:16.591276Z","end":"2026-05-05T18:20:16.941759Z","steps":["trace[1849359324] 'read index received' (duration: 350.311981ms)","trace[1849359324] 'applied index is now lower than readState.Index' (duration: 169.855µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:16.941861Z","caller":"traceutil/trace.go:171","msg":"trace[503331100] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"364.145666ms","start":"2026-05-05T18:20:16.577685Z","end":"2026-05-05T18:20:16.94183Z","steps":["trace[503331100] 'process raft request' (duration: 363.947591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:16.942168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.577657Z","time spent":"364.461646ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:20:16.941989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.706073ms","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-05-05T18:20:16.942332Z","caller":"traceutil/trace.go:171","msg":"trace[145636852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1304; }","duration":"351.195876ms","start":"2026-05-05T18:20:16.591129Z","end":"2026-05-05T18:20:16.942324Z","steps":["trace[145636852] 'agreement among raft nodes before linearized reading' (duration: 350.712423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:16.942356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.591111Z","time spent":"351.238966ms","remote":"127.0.0.1:36730","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-05-05T18:20:16.942637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.634694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:20:16.942663Z","caller":"traceutil/trace.go:171","msg":"trace[553032402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"338.674915ms","start":"2026-05-05T18:20:16.603981Z","end":"2026-05-05T18:20:16.942655Z","steps":["trace[553032402] 'agreement among raft nodes before linearized reading' (duration: 338.580472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:16.942681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.603974Z","time spent":"338.702945ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T18:20:16.942854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.175589ms","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-05-05T18:20:16.94288Z","caller":"traceutil/trace.go:171","msg":"trace[1636504816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1304; }","duration":"339.22763ms","start":"2026-05-05T18:20:16.603646Z","end":"2026-05-05T18:20:16.942873Z","steps":["trace[1636504816] 'agreement among raft nodes before linearized reading' (duration: 339.163119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:16.942898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.603631Z","time spent":"339.261521ms","remote":"127.0.0.1:36730","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-05-05T18:20:16.943062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.203099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T18:20:16.943005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.488306ms","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-05-05T18:20:16.943086Z","caller":"traceutil/trace.go:171","msg":"trace[1099093228] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1304; }","duration":"278.230219ms","start":"2026-05-05T18:20:16.66485Z","end":"2026-05-05T18:20:16.94308Z","steps":["trace[1099093228] 'agreement among raft nodes before linearized reading' (duration: 278.186468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:16.943051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.124977ms","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-05-05T18:20:16.943156Z","caller":"traceutil/trace.go:171","msg":"trace[1526159881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"339.27664ms","start":"2026-05-05T18:20:16.603856Z","end":"2026-05-05T18:20:16.943132Z","steps":["trace[1526159881] 'agreement among raft nodes before linearized reading' (duration: 338.943311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:16.943217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.603844Z","time spent":"339.360953ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T18:20:16.943336Z","caller":"traceutil/trace.go:171","msg":"trace[1242829782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"339.947018ms","start":"2026-05-05T18:20:16.603151Z","end":"2026-05-05T18:20:16.943098Z","steps":["trace[1242829782] 'agreement among raft nodes before linearized reading' (duration: 339.205418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:16.943496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.603128Z","time spent":"340.324698ms","remote":"127.0.0.1:36730","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-05-05T18:20:17.64276Z","caller":"traceutil/trace.go:171","msg":"trace[2142748518] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"693.922288ms","start":"2026-05-05T18:20:16.948821Z","end":"2026-05-05T18:20:17.642743Z","steps":["trace[2142748518] 'process raft request' (duration: 693.741703ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:17.642759Z","caller":"traceutil/trace.go:171","msg":"trace[889728608] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1360; }","duration":"277.406409ms","start":"2026-05-05T18:20:17.365334Z","end":"2026-05-05T18:20:17.642741Z","steps":["trace[889728608] 'read index received' (duration: 277.168382ms)","trace[889728608] 'applied index is now lower than readState.Index' (duration: 237.377µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:17.64283Z","caller":"traceutil/trace.go:171","msg":"trace[389038555] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"693.666971ms","start":"2026-05-05T18:20:16.949131Z","end":"2026-05-05T18:20:17.642798Z","steps":["trace[389038555] 'process raft request' (duration: 693.558888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:17.642869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.542332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:20:17.642871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.948806Z","time spent":"694.00694ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:17.642894Z","caller":"traceutil/trace.go:171","msg":"trace[82164888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"277.609404ms","start":"2026-05-05T18:20:17.365278Z","end":"2026-05-05T18:20:17.642887Z","steps":["trace[82164888] 'agreement among raft nodes before linearized reading' (duration: 277.563873ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:17.64288Z","caller":"traceutil/trace.go:171","msg":"trace[664360581] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"693.750983ms","start":"2026-05-05T18:20:16.949098Z","end":"2026-05-05T18:20:17.642848Z","steps":["trace[664360581] 'process raft request' (duration: 693.61989ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:17.642973Z","caller":"traceutil/trace.go:171","msg":"trace[99692915] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"694.010499ms","start":"2026-05-05T18:20:16.948944Z","end":"2026-05-05T18:20:17.642955Z","steps":["trace[99692915] 'process raft request' (duration: 693.72047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:17.643154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.948935Z","time spent":"694.196644ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:20:17.642983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.949106Z","time spent":"693.790853ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:20:17.643048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:16.949035Z","time spent":"693.931057ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:20:17.994751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.171121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T18:20:17.994756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.03706ms","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:509"} {"level":"info","ts":"2026-05-05T18:20:17.994829Z","caller":"traceutil/trace.go:171","msg":"trace[1858421822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1309; }","duration":"230.288935ms","start":"2026-05-05T18:20:17.764522Z","end":"2026-05-05T18:20:17.994811Z","steps":["trace[1858421822] 'count revisions from in-memory index tree' (duration: 230.11644ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:17.994869Z","caller":"traceutil/trace.go:171","msg":"trace[2057571308] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1309; }","duration":"235.204806ms","start":"2026-05-05T18:20:17.759643Z","end":"2026-05-05T18:20:17.994848Z","steps":["trace[2057571308] 'range keys from in-memory index tree' (duration: 234.844475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:24.399268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.236472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T18:20:24.399303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.953574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T18:20:24.399329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.894444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:20:24.399358Z","caller":"traceutil/trace.go:171","msg":"trace[1026455416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"725.364588ms","start":"2026-05-05T18:20:23.673971Z","end":"2026-05-05T18:20:24.399335Z","steps":["trace[1026455416] 'range keys from in-memory index tree' (duration: 725.057247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:24.399402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:23.673956Z","time spent":"725.436169ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T18:20:24.399404Z","caller":"traceutil/trace.go:171","msg":"trace[124381188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"728.000176ms","start":"2026-05-05T18:20:23.671384Z","end":"2026-05-05T18:20:24.399384Z","steps":["trace[124381188] 'range keys from in-memory index tree' (duration: 727.755739ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:24.399398Z","caller":"traceutil/trace.go:171","msg":"trace[2146557302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1350; }","duration":"728.109929ms","start":"2026-05-05T18:20:23.671263Z","end":"2026-05-05T18:20:24.399373Z","steps":["trace[2146557302] 'range keys from in-memory index tree' (duration: 727.757519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:24.399449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:23.671369Z","time spent":"728.067588ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T18:20:24.39945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:23.67124Z","time spent":"728.199801ms","remote":"127.0.0.1:36730","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-05-05T18:20:24.399521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.189824ms","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-05-05T18:20:24.399579Z","caller":"traceutil/trace.go:171","msg":"trace[573346204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1350; }","duration":"730.282216ms","start":"2026-05-05T18:20:23.669282Z","end":"2026-05-05T18:20:24.399564Z","steps":["trace[573346204] 'range keys from in-memory index tree' (duration: 730.03952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:24.399626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:23.669261Z","time spent":"730.352898ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T18:20:24.399805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.158764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:20:24.399876Z","caller":"traceutil/trace.go:171","msg":"trace[805710040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"658.936334ms","start":"2026-05-05T18:20:23.740921Z","end":"2026-05-05T18:20:24.399857Z","steps":["trace[805710040] 'range keys from in-memory index tree' (duration: 658.055301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:24.399925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:23.740907Z","time spent":"659.007836ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T18:20:25.341474Z","caller":"traceutil/trace.go:171","msg":"trace[764302718] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"937.010229ms","start":"2026-05-05T18:20:24.404424Z","end":"2026-05-05T18:20:25.341434Z","steps":["trace[764302718] 'process raft request' (duration: 936.848465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:25.341618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:24.40441Z","time spent":"937.138263ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:25.647892Z","caller":"traceutil/trace.go:171","msg":"trace[1029546883] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"1.242807778s","start":"2026-05-05T18:20:24.405061Z","end":"2026-05-05T18:20:25.647869Z","steps":["trace[1029546883] 'process raft request' (duration: 1.242631654s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:25.648033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:24.405054Z","time spent":"1.24290815s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:25.648576Z","caller":"traceutil/trace.go:171","msg":"trace[813503171] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1406; }","duration":"642.218363ms","start":"2026-05-05T18:20:25.006329Z","end":"2026-05-05T18:20:25.648548Z","steps":["trace[813503171] 'read index received' (duration: 335.049289ms)","trace[813503171] 'applied index is now lower than readState.Index' (duration: 307.167584ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:25.648637Z","caller":"traceutil/trace.go:171","msg":"trace[399723502] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"1.243076485s","start":"2026-05-05T18:20:24.405547Z","end":"2026-05-05T18:20:25.648624Z","steps":["trace[399723502] 'process raft request' (duration: 1.242278994s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:25.648708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:24.405518Z","time spent":"1.243152847s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:25.648721Z","caller":"traceutil/trace.go:171","msg":"trace[1566518416] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.242954811s","start":"2026-05-05T18:20:24.405749Z","end":"2026-05-05T18:20:25.648704Z","steps":["trace[1566518416] 'process raft request' (duration: 1.242464238s)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:25.649075Z","caller":"traceutil/trace.go:171","msg":"trace[1579728095] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"678.438958ms","start":"2026-05-05T18:20:24.970605Z","end":"2026-05-05T18:20:25.649044Z","steps":["trace[1579728095] 'process raft request' (duration: 677.891924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:25.649178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.857159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T18:20:25.649214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:24.970574Z","time spent":"678.565902ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:25.649223Z","caller":"traceutil/trace.go:171","msg":"trace[467878137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"642.956283ms","start":"2026-05-05T18:20:25.006254Z","end":"2026-05-05T18:20:25.64921Z","steps":["trace[467878137] 'agreement among raft nodes before linearized reading' (duration: 642.81993ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:25.649209Z","caller":"traceutil/trace.go:171","msg":"trace[619639772] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.243065145s","start":"2026-05-05T18:20:24.40612Z","end":"2026-05-05T18:20:25.649185Z","steps":["trace[619639772] 'process raft request' (duration: 1.242327505s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:25.649256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:25.006194Z","time spent":"643.054625ms","remote":"127.0.0.1:36730","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-05-05T18:20:25.649235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.015139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:20:25.649324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:24.406109Z","time spent":"1.243164007s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:25.649347Z","caller":"traceutil/trace.go:171","msg":"trace[1768402047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"285.187143ms","start":"2026-05-05T18:20:25.36414Z","end":"2026-05-05T18:20:25.649327Z","steps":["trace[1768402047] 'agreement among raft nodes before linearized reading' (duration: 285.000679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:25.649482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:24.405732Z","time spent":"1.243218258s","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:20:25.649635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.08345ms","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":"warn","ts":"2026-05-05T18:20:25.649665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.908176ms","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-05-05T18:20:25.649684Z","caller":"traceutil/trace.go:171","msg":"trace[2047394014] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1356; }","duration":"136.158993ms","start":"2026-05-05T18:20:25.513515Z","end":"2026-05-05T18:20:25.649674Z","steps":["trace[2047394014] 'agreement among raft nodes before linearized reading' (duration: 136.07505ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:25.649699Z","caller":"traceutil/trace.go:171","msg":"trace[10225451] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1356; }","duration":"143.976038ms","start":"2026-05-05T18:20:25.505713Z","end":"2026-05-05T18:20:25.649689Z","steps":["trace[10225451] 'agreement among raft nodes before linearized reading' (duration: 143.887295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:29.84385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.384738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:20:29.844084Z","caller":"traceutil/trace.go:171","msg":"trace[610043415] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1429; }","duration":"115.096407ms","start":"2026-05-05T18:20:29.728966Z","end":"2026-05-05T18:20:29.844063Z","steps":["trace[610043415] 'read index received' (duration: 176.466µs)","trace[610043415] 'applied index is now lower than readState.Index' (duration: 114.918431ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:29.84425Z","caller":"traceutil/trace.go:171","msg":"trace[926807392] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"158.552883ms","start":"2026-05-05T18:20:29.685669Z","end":"2026-05-05T18:20:29.844222Z","steps":["trace[926807392] 'process raft request' (duration: 158.304127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:29.844351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.372844ms","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-05-05T18:20:29.844372Z","caller":"traceutil/trace.go:171","msg":"trace[1227406482] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"159.293123ms","start":"2026-05-05T18:20:29.685053Z","end":"2026-05-05T18:20:29.844346Z","steps":["trace[1227406482] 'process raft request' (duration: 44.081533ms)","trace[1227406482] 'compare' (duration: 114.244554ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:29.844404Z","caller":"traceutil/trace.go:171","msg":"trace[123709647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1374; }","duration":"115.466227ms","start":"2026-05-05T18:20:29.728923Z","end":"2026-05-05T18:20:29.844389Z","steps":["trace[123709647] 'agreement among raft nodes before linearized reading' (duration: 115.299252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:29.844624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.570149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:20:29.844666Z","caller":"traceutil/trace.go:171","msg":"trace[358380848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"115.637832ms","start":"2026-05-05T18:20:29.729015Z","end":"2026-05-05T18:20:29.844653Z","steps":["trace[358380848] 'agreement among raft nodes before linearized reading' (duration: 115.517358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:29.844677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.408515ms","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-05-05T18:20:29.844727Z","caller":"traceutil/trace.go:171","msg":"trace[1331572632] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1374; }","duration":"115.487877ms","start":"2026-05-05T18:20:29.729223Z","end":"2026-05-05T18:20:29.844711Z","steps":["trace[1331572632] 'agreement among raft nodes before linearized reading' (duration: 115.357762ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:30.025971Z","caller":"traceutil/trace.go:171","msg":"trace[1009026020] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"176.791354ms","start":"2026-05-05T18:20:29.84915Z","end":"2026-05-05T18:20:30.025941Z","steps":["trace[1009026020] 'process raft request' (duration: 175.414748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:30.25896Z","caller":"traceutil/trace.go:171","msg":"trace[1958447262] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"409.263907ms","start":"2026-05-05T18:20:29.849677Z","end":"2026-05-05T18:20:30.258941Z","steps":["trace[1958447262] 'process raft request' (duration: 409.225486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:30.259247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:29.849672Z","time spent":"409.349059ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:30.259683Z","caller":"traceutil/trace.go:171","msg":"trace[1789057609] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"410.184231ms","start":"2026-05-05T18:20:29.84949Z","end":"2026-05-05T18:20:30.259674Z","steps":["trace[1789057609] 'process raft request' (duration: 409.281808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:30.259732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:29.849467Z","time spent":"410.240363ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:32.017506Z","caller":"traceutil/trace.go:171","msg":"trace[1814069815] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"164.364157ms","start":"2026-05-05T18:20:31.853095Z","end":"2026-05-05T18:20:32.017459Z","steps":["trace[1814069815] 'process raft request' (duration: 159.651022ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:42.720598Z","caller":"traceutil/trace.go:171","msg":"trace[210180940] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"127.463223ms","start":"2026-05-05T18:20:42.593113Z","end":"2026-05-05T18:20:42.720576Z","steps":["trace[210180940] 'process raft request' (duration: 127.316009ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:45.590184Z","caller":"traceutil/trace.go:171","msg":"trace[2104288299] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"110.501986ms","start":"2026-05-05T18:20:45.479655Z","end":"2026-05-05T18:20:45.590157Z","steps":["trace[2104288299] 'process raft request' (duration: 110.317721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:45.874295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.021482ms","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:526"} {"level":"info","ts":"2026-05-05T18:20:45.874357Z","caller":"traceutil/trace.go:171","msg":"trace[10179241] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1444; }","duration":"160.138455ms","start":"2026-05-05T18:20:45.714205Z","end":"2026-05-05T18:20:45.874344Z","steps":["trace[10179241] 'range keys from in-memory index tree' (duration: 159.859788ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:45.93051Z","caller":"traceutil/trace.go:171","msg":"trace[300709799] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"114.640265ms","start":"2026-05-05T18:20:45.815847Z","end":"2026-05-05T18:20:45.930487Z","steps":["trace[300709799] 'process raft request' (duration: 114.483471ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:46.13088Z","caller":"traceutil/trace.go:171","msg":"trace[1248304852] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"126.352323ms","start":"2026-05-05T18:20:46.004507Z","end":"2026-05-05T18:20:46.130859Z","steps":["trace[1248304852] 'read index received' (duration: 126.042365ms)","trace[1248304852] 'applied index is now lower than readState.Index' (duration: 309.068µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:46.130972Z","caller":"traceutil/trace.go:171","msg":"trace[518322611] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"251.442404ms","start":"2026-05-05T18:20:45.879502Z","end":"2026-05-05T18:20:46.130945Z","steps":["trace[518322611] 'process raft request' (duration: 251.199547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:46.13112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.59552ms","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-05-05T18:20:46.13119Z","caller":"traceutil/trace.go:171","msg":"trace[596571733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1446; }","duration":"126.707863ms","start":"2026-05-05T18:20:46.004466Z","end":"2026-05-05T18:20:46.131174Z","steps":["trace[596571733] 'agreement among raft nodes before linearized reading' (duration: 126.533088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:46.533158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.963792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:20:46.533405Z","caller":"traceutil/trace.go:171","msg":"trace[679681395] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"398.575725ms","start":"2026-05-05T18:20:46.134802Z","end":"2026-05-05T18:20:46.533378Z","steps":["trace[679681395] 'process raft request' (duration: 100.310287ms)","trace[679681395] 'compare' (duration: 297.530889ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:20:46.53354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.134787Z","time spent":"398.677467ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:46.533706Z","caller":"traceutil/trace.go:171","msg":"trace[243581289] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1506; }","duration":"368.920293ms","start":"2026-05-05T18:20:46.164728Z","end":"2026-05-05T18:20:46.533648Z","steps":["trace[243581289] 'read index received' (duration: 70.371086ms)","trace[243581289] 'applied index is now lower than readState.Index' (duration: 298.548327ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:46.533668Z","caller":"traceutil/trace.go:171","msg":"trace[1607121752] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"397.9924ms","start":"2026-05-05T18:20:46.13565Z","end":"2026-05-05T18:20:46.533642Z","steps":["trace[1607121752] 'process raft request' (duration: 397.6429ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:46.53383Z","caller":"traceutil/trace.go:171","msg":"trace[570303065] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"398.091032ms","start":"2026-05-05T18:20:46.135728Z","end":"2026-05-05T18:20:46.533819Z","steps":["trace[570303065] 'process raft request' (duration: 397.786535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:46.533922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.135633Z","time spent":"398.195886ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:46.534062Z","caller":"traceutil/trace.go:171","msg":"trace[1580575846] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"397.728833ms","start":"2026-05-05T18:20:46.13632Z","end":"2026-05-05T18:20:46.534049Z","steps":["trace[1580575846] 'process raft request' (duration: 397.260571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:46.5341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.343964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-05T18:20:46.534128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.136307Z","time spent":"397.787604ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:46.534179Z","caller":"traceutil/trace.go:171","msg":"trace[727884109] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1450; }","duration":"369.474577ms","start":"2026-05-05T18:20:46.164687Z","end":"2026-05-05T18:20:46.534162Z","steps":["trace[727884109] 'agreement among raft nodes before linearized reading' (duration: 369.20641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:46.534129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.135701Z","time spent":"398.211156ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:20:46.534228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.16467Z","time spent":"369.549149ms","remote":"127.0.0.1:36516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-05-05T18:20:46.534342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.45676ms","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-05-05T18:20:46.534388Z","caller":"traceutil/trace.go:171","msg":"trace[514649706] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1450; }","duration":"205.521662ms","start":"2026-05-05T18:20:46.32885Z","end":"2026-05-05T18:20:46.534372Z","steps":["trace[514649706] 'agreement among raft nodes before linearized reading' (duration: 205.370988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:46.534482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.298227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:20:46.534517Z","caller":"traceutil/trace.go:171","msg":"trace[789537416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1450; }","duration":"169.371279ms","start":"2026-05-05T18:20:46.365135Z","end":"2026-05-05T18:20:46.534506Z","steps":["trace[789537416] 'agreement among raft nodes before linearized reading' (duration: 169.326177ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:46.845009Z","caller":"traceutil/trace.go:171","msg":"trace[618815486] linearizableReadLoop","detail":"{readStateIndex:1511; appliedIndex:1510; }","duration":"308.58113ms","start":"2026-05-05T18:20:46.536374Z","end":"2026-05-05T18:20:46.844955Z","steps":["trace[618815486] 'read index received' (duration: 229.105854ms)","trace[618815486] 'applied index is now lower than readState.Index' (duration: 79.474046ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:20:46.845052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.53507Z","time spent":"309.975218ms","remote":"127.0.0.1:36516","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-05T18:20:46.845161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.760806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:20:46.845253Z","caller":"traceutil/trace.go:171","msg":"trace[963012166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1450; }","duration":"308.884618ms","start":"2026-05-05T18:20:46.536347Z","end":"2026-05-05T18:20:46.845232Z","steps":["trace[963012166] 'agreement among raft nodes before linearized reading' (duration: 308.734905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:46.845315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.536331Z","time spent":"308.966751ms","remote":"127.0.0.1:36476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T18:20:47.077903Z","caller":"traceutil/trace.go:171","msg":"trace[1407955877] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"536.529134ms","start":"2026-05-05T18:20:46.541338Z","end":"2026-05-05T18:20:47.077867Z","steps":["trace[1407955877] 'process raft request' (duration: 466.936079ms)","trace[1407955877] 'compare' (duration: 69.323118ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:47.077942Z","caller":"traceutil/trace.go:171","msg":"trace[401422496] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"536.530944ms","start":"2026-05-05T18:20:46.541375Z","end":"2026-05-05T18:20:47.077906Z","steps":["trace[401422496] 'process raft request' (duration: 536.418521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:47.078051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.54131Z","time spent":"536.662998ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:47.078081Z","caller":"traceutil/trace.go:171","msg":"trace[2027027047] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"232.201425ms","start":"2026-05-05T18:20:46.845858Z","end":"2026-05-05T18:20:47.078059Z","steps":["trace[2027027047] 'process raft request' (duration: 232.122993ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:47.078279Z","caller":"traceutil/trace.go:171","msg":"trace[1508309102] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"536.862724ms","start":"2026-05-05T18:20:46.541395Z","end":"2026-05-05T18:20:47.078258Z","steps":["trace[1508309102] 'process raft request' (duration: 536.457353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:47.078542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.541365Z","time spent":"537.086639ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:20:47.07932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:46.541351Z","time spent":"536.697179ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:20:47.782535Z","caller":"traceutil/trace.go:171","msg":"trace[310172955] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"183.363727ms","start":"2026-05-05T18:20:47.599145Z","end":"2026-05-05T18:20:47.782509Z","steps":["trace[310172955] 'process raft request' (duration: 183.220623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:48.247774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.523858ms","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:508"} {"level":"info","ts":"2026-05-05T18:20:48.247875Z","caller":"traceutil/trace.go:171","msg":"trace[890501103] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1456; }","duration":"134.642361ms","start":"2026-05-05T18:20:48.113208Z","end":"2026-05-05T18:20:48.24785Z","steps":["trace[890501103] 'range keys from in-memory index tree' (duration: 134.287873ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:48.360078Z","caller":"traceutil/trace.go:171","msg":"trace[1083605154] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"107.893117ms","start":"2026-05-05T18:20:48.252156Z","end":"2026-05-05T18:20:48.360049Z","steps":["trace[1083605154] 'process raft request' (duration: 107.735102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:48.78075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.340636ms","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-05-05T18:20:48.780831Z","caller":"traceutil/trace.go:171","msg":"trace[1889066676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1457; }","duration":"241.459061ms","start":"2026-05-05T18:20:48.539353Z","end":"2026-05-05T18:20:48.780812Z","steps":["trace[1889066676] 'range keys from in-memory index tree' (duration: 241.169912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:48.780812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.335163ms","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":"warn","ts":"2026-05-05T18:20:48.781022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.400445ms","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-05-05T18:20:48.781059Z","caller":"traceutil/trace.go:171","msg":"trace[936465978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1457; }","duration":"242.436946ms","start":"2026-05-05T18:20:48.538611Z","end":"2026-05-05T18:20:48.781048Z","steps":["trace[936465978] 'range keys from in-memory index tree' (duration: 242.170969ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:48.78125Z","caller":"traceutil/trace.go:171","msg":"trace[1189884834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1457; }","duration":"242.462427ms","start":"2026-05-05T18:20:48.538407Z","end":"2026-05-05T18:20:48.780869Z","steps":["trace[1189884834] 'range keys from in-memory index tree' (duration: 242.159499ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:48.938826Z","caller":"traceutil/trace.go:171","msg":"trace[2046679509] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"152.893233ms","start":"2026-05-05T18:20:48.785909Z","end":"2026-05-05T18:20:48.938802Z","steps":["trace[2046679509] 'process raft request' (duration: 152.727319ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:49.066484Z","caller":"traceutil/trace.go:171","msg":"trace[961288018] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"280.232543ms","start":"2026-05-05T18:20:48.786229Z","end":"2026-05-05T18:20:49.066461Z","steps":["trace[961288018] 'process raft request' (duration: 227.763339ms)","trace[961288018] 'compare' (duration: 52.287829ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:49.066606Z","caller":"traceutil/trace.go:171","msg":"trace[8591377] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"279.423532ms","start":"2026-05-05T18:20:48.787143Z","end":"2026-05-05T18:20:49.066566Z","steps":["trace[8591377] 'process raft request' (duration: 279.237446ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:52.31828Z","caller":"traceutil/trace.go:171","msg":"trace[485195434] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1535; }","duration":"234.106376ms","start":"2026-05-05T18:20:52.084151Z","end":"2026-05-05T18:20:52.318257Z","steps":["trace[485195434] 'read index received' (duration: 233.907111ms)","trace[485195434] 'applied index is now lower than readState.Index' (duration: 198.295µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:52.318407Z","caller":"traceutil/trace.go:171","msg":"trace[1408713049] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"280.297076ms","start":"2026-05-05T18:20:52.038077Z","end":"2026-05-05T18:20:52.318374Z","steps":["trace[1408713049] 'process raft request' (duration: 280.041538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:52.318493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.340143ms","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-05-05T18:20:52.318542Z","caller":"traceutil/trace.go:171","msg":"trace[77722420] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1474; }","duration":"234.399754ms","start":"2026-05-05T18:20:52.084122Z","end":"2026-05-05T18:20:52.318522Z","steps":["trace[77722420] 'agreement among raft nodes before linearized reading' (duration: 234.250811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:52.318577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.611488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:20:52.318695Z","caller":"traceutil/trace.go:171","msg":"trace[323674053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1474; }","duration":"146.799193ms","start":"2026-05-05T18:20:52.17187Z","end":"2026-05-05T18:20:52.318669Z","steps":["trace[323674053] 'agreement among raft nodes before linearized reading' (duration: 146.586488ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:52.696736Z","caller":"traceutil/trace.go:171","msg":"trace[1561867406] linearizableReadLoop","detail":"{readStateIndex:1537; appliedIndex:1536; }","duration":"358.500308ms","start":"2026-05-05T18:20:52.338214Z","end":"2026-05-05T18:20:52.696714Z","steps":["trace[1561867406] 'read index received' (duration: 358.272912ms)","trace[1561867406] 'applied index is now lower than readState.Index' (duration: 226.336µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:52.696795Z","caller":"traceutil/trace.go:171","msg":"trace[1931816005] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"373.925645ms","start":"2026-05-05T18:20:52.322826Z","end":"2026-05-05T18:20:52.696752Z","steps":["trace[1931816005] 'process raft request' (duration: 373.711939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:52.696948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:52.322788Z","time spent":"374.073638ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:20:52.696949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.308103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:20:52.697039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.748214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:20:52.697065Z","caller":"traceutil/trace.go:171","msg":"trace[611580498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1475; }","duration":"333.451227ms","start":"2026-05-05T18:20:52.36359Z","end":"2026-05-05T18:20:52.697041Z","steps":["trace[611580498] 'agreement among raft nodes before linearized reading' (duration: 333.282663ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:52.697107Z","caller":"traceutil/trace.go:171","msg":"trace[665633352] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1475; }","duration":"358.906848ms","start":"2026-05-05T18:20:52.338183Z","end":"2026-05-05T18:20:52.69709Z","steps":["trace[665633352] 'agreement among raft nodes before linearized reading' (duration: 358.726784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:20:52.697117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:52.363578Z","time spent":"333.528908ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:20:52.697152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:20:52.338165Z","time spent":"358.97722ms","remote":"127.0.0.1:36680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-05T18:20:55.294504Z","caller":"traceutil/trace.go:171","msg":"trace[525540336] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"115.433626ms","start":"2026-05-05T18:20:55.179046Z","end":"2026-05-05T18:20:55.294479Z","steps":["trace[525540336] 'process raft request' (duration: 115.138488ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:55.324389Z","caller":"traceutil/trace.go:171","msg":"trace[1438679465] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"142.411027ms","start":"2026-05-05T18:20:55.18196Z","end":"2026-05-05T18:20:55.324371Z","steps":["trace[1438679465] 'process raft request' (duration: 142.319134ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:55.324389Z","caller":"traceutil/trace.go:171","msg":"trace[788573045] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"143.735572ms","start":"2026-05-05T18:20:55.18063Z","end":"2026-05-05T18:20:55.324365Z","steps":["trace[788573045] 'process raft request' (duration: 143.469575ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:55.324353Z","caller":"traceutil/trace.go:171","msg":"trace[378298124] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"142.52347ms","start":"2026-05-05T18:20:55.181801Z","end":"2026-05-05T18:20:55.324324Z","steps":["trace[378298124] 'process raft request' (duration: 142.427657ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:59.556482Z","caller":"traceutil/trace.go:171","msg":"trace[320288082] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"187.499177ms","start":"2026-05-05T18:20:59.368954Z","end":"2026-05-05T18:20:59.556454Z","steps":["trace[320288082] 'process raft request' (duration: 155.76186ms)","trace[320288082] 'compare' (duration: 31.617094ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:20:59.619439Z","caller":"traceutil/trace.go:171","msg":"trace[253972908] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"180.110341ms","start":"2026-05-05T18:20:59.439291Z","end":"2026-05-05T18:20:59.619401Z","steps":["trace[253972908] 'process raft request' (duration: 180.07035ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:59.61949Z","caller":"traceutil/trace.go:171","msg":"trace[1567646162] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"250.433667ms","start":"2026-05-05T18:20:59.369014Z","end":"2026-05-05T18:20:59.619447Z","steps":["trace[1567646162] 'process raft request' (duration: 250.190571ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:20:59.619559Z","caller":"traceutil/trace.go:171","msg":"trace[1614918256] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"250.449927ms","start":"2026-05-05T18:20:59.369076Z","end":"2026-05-05T18:20:59.619526Z","steps":["trace[1614918256] 'process raft request' (duration: 250.248632ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:01.584939Z","caller":"traceutil/trace.go:171","msg":"trace[547131878] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"154.925647ms","start":"2026-05-05T18:21:01.429989Z","end":"2026-05-05T18:21:01.584915Z","steps":["trace[547131878] 'process raft request' (duration: 154.529167ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:01.740654Z","caller":"traceutil/trace.go:171","msg":"trace[135068236] linearizableReadLoop","detail":"{readStateIndex:1578; appliedIndex:1577; }","duration":"126.688962ms","start":"2026-05-05T18:21:01.613945Z","end":"2026-05-05T18:21:01.740634Z","steps":["trace[135068236] 'read index received' (duration: 126.164488ms)","trace[135068236] 'applied index is now lower than readState.Index' (duration: 523.594µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:21:01.740744Z","caller":"traceutil/trace.go:171","msg":"trace[301031627] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"152.278676ms","start":"2026-05-05T18:21:01.588437Z","end":"2026-05-05T18:21:01.740715Z","steps":["trace[301031627] 'process raft request' (duration: 151.763982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:21:01.740902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.944308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-05T18:21:01.740948Z","caller":"traceutil/trace.go:171","msg":"trace[2062452446] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1515; }","duration":"127.045771ms","start":"2026-05-05T18:21:01.613891Z","end":"2026-05-05T18:21:01.740937Z","steps":["trace[2062452446] 'agreement among raft nodes before linearized reading' (duration: 126.853966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:21:01.740954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.095545ms","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-05-05T18:21:01.741035Z","caller":"traceutil/trace.go:171","msg":"trace[1140061524] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1515; }","duration":"118.222609ms","start":"2026-05-05T18:21:01.622799Z","end":"2026-05-05T18:21:01.741022Z","steps":["trace[1140061524] 'agreement among raft nodes before linearized reading' (duration: 118.018963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:21:01.741047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.918902ms","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-05-05T18:21:01.741121Z","caller":"traceutil/trace.go:171","msg":"trace[193162990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1515; }","duration":"118.022174ms","start":"2026-05-05T18:21:01.62308Z","end":"2026-05-05T18:21:01.741102Z","steps":["trace[193162990] 'agreement among raft nodes before linearized reading' (duration: 117.758796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:21:02.367969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.889317ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-05T18:21:07.910723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.444527ms","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-05-05T18:21:07.910801Z","caller":"traceutil/trace.go:171","msg":"trace[2005117904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1542; }","duration":"145.53805ms","start":"2026-05-05T18:21:07.765244Z","end":"2026-05-05T18:21:07.910782Z","steps":["trace[2005117904] 'range keys from in-memory index tree' (duration: 145.244172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:21:07.91091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.526554ms","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-05-05T18:21:07.910975Z","caller":"traceutil/trace.go:171","msg":"trace[558956853] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1542; }","duration":"163.653348ms","start":"2026-05-05T18:21:07.747305Z","end":"2026-05-05T18:21:07.910959Z","steps":["trace[558956853] 'range keys from in-memory index tree' (duration: 163.36807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:21:07.911016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.58519ms","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-05-05T18:21:07.911057Z","caller":"traceutil/trace.go:171","msg":"trace[390298912] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1542; }","duration":"123.665062ms","start":"2026-05-05T18:21:07.787382Z","end":"2026-05-05T18:21:07.911047Z","steps":["trace[390298912] 'range keys from in-memory index tree' (duration: 123.372135ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:08.15267Z","caller":"traceutil/trace.go:171","msg":"trace[1546336388] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"237.424253ms","start":"2026-05-05T18:21:07.915226Z","end":"2026-05-05T18:21:08.15265Z","steps":["trace[1546336388] 'process raft request' (duration: 237.3115ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:08.15267Z","caller":"traceutil/trace.go:171","msg":"trace[512602961] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"237.608569ms","start":"2026-05-05T18:21:07.915019Z","end":"2026-05-05T18:21:08.152627Z","steps":["trace[512602961] 'process raft request' (duration: 219.053619ms)","trace[512602961] 'compare' (duration: 18.338463ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:21:08.152676Z","caller":"traceutil/trace.go:171","msg":"trace[1723614669] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"237.367382ms","start":"2026-05-05T18:21:07.915283Z","end":"2026-05-05T18:21:08.152651Z","steps":["trace[1723614669] 'process raft request' (duration: 237.29573ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:08.317311Z","caller":"traceutil/trace.go:171","msg":"trace[1706884298] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"155.733208ms","start":"2026-05-05T18:21:08.161548Z","end":"2026-05-05T18:21:08.317281Z","steps":["trace[1706884298] 'process raft request' (duration: 108.122202ms)","trace[1706884298] 'compare' (duration: 47.505253ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:21:08.630878Z","caller":"traceutil/trace.go:171","msg":"trace[924402287] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"189.043047ms","start":"2026-05-05T18:21:08.441813Z","end":"2026-05-05T18:21:08.630856Z","steps":["trace[924402287] 'process raft request' (duration: 188.917214ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:10.142534Z","caller":"traceutil/trace.go:171","msg":"trace[1482402146] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"161.611333ms","start":"2026-05-05T18:21:09.980887Z","end":"2026-05-05T18:21:10.142499Z","steps":["trace[1482402146] 'process raft request' (duration: 131.88713ms)","trace[1482402146] 'compare' (duration: 29.616941ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:21:30.438065Z","caller":"traceutil/trace.go:171","msg":"trace[1814928531] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"133.852403ms","start":"2026-05-05T18:21:30.304185Z","end":"2026-05-05T18:21:30.438037Z","steps":["trace[1814928531] 'process raft request' (duration: 133.711189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:21:30.438217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.54758ms","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-05-05T18:21:30.438282Z","caller":"traceutil/trace.go:171","msg":"trace[1679054673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1645; }","duration":"114.661203ms","start":"2026-05-05T18:21:30.323604Z","end":"2026-05-05T18:21:30.438265Z","steps":["trace[1679054673] 'agreement among raft nodes before linearized reading' (duration: 114.505339ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:30.438069Z","caller":"traceutil/trace.go:171","msg":"trace[1467131410] linearizableReadLoop","detail":"{readStateIndex:1714; appliedIndex:1713; }","duration":"114.385926ms","start":"2026-05-05T18:21:30.323661Z","end":"2026-05-05T18:21:30.438047Z","steps":["trace[1467131410] 'read index received' (duration: 114.112118ms)","trace[1467131410] 'applied index is now lower than readState.Index' (duration: 272.718µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:21:30.617999Z","caller":"traceutil/trace.go:171","msg":"trace[89655485] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"174.458409ms","start":"2026-05-05T18:21:30.443511Z","end":"2026-05-05T18:21:30.617969Z","steps":["trace[89655485] 'process raft request' (duration: 174.401547ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:30.618442Z","caller":"traceutil/trace.go:171","msg":"trace[191949406] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"175.016733ms","start":"2026-05-05T18:21:30.443364Z","end":"2026-05-05T18:21:30.618381Z","steps":["trace[191949406] 'process raft request' (duration: 174.50416ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:21:30.618112Z","caller":"traceutil/trace.go:171","msg":"trace[1976410074] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"174.805348ms","start":"2026-05-05T18:21:30.443274Z","end":"2026-05-05T18:21:30.618079Z","steps":["trace[1976410074] 'process raft request' (duration: 116.85811ms)","trace[1976410074] 'compare' (duration: 57.600568ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:22:22.246291Z","caller":"traceutil/trace.go:171","msg":"trace[279862438] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"121.095842ms","start":"2026-05-05T18:22:22.125164Z","end":"2026-05-05T18:22:22.246259Z","steps":["trace[279862438] 'process raft request' (duration: 120.889106ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:22:25.934959Z","caller":"traceutil/trace.go:171","msg":"trace[510531398] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"154.562317ms","start":"2026-05-05T18:22:25.780366Z","end":"2026-05-05T18:22:25.934929Z","steps":["trace[510531398] 'process raft request' (duration: 154.299831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:22:26.468688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.59481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:22:26.468784Z","caller":"traceutil/trace.go:171","msg":"trace[466385425] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"181.72693ms","start":"2026-05-05T18:22:26.287043Z","end":"2026-05-05T18:22:26.46877Z","steps":["trace[466385425] 'process raft request' (duration: 53.987356ms)","trace[466385425] 'compare' (duration: 127.466277ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:22:26.731558Z","caller":"traceutil/trace.go:171","msg":"trace[2072711825] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1981; }","duration":"367.846663ms","start":"2026-05-05T18:22:26.363689Z","end":"2026-05-05T18:22:26.731536Z","steps":["trace[2072711825] 'read index received' (duration: 313.673612ms)","trace[2072711825] 'applied index is now lower than readState.Index' (duration: 54.172001ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:22:26.731858Z","caller":"traceutil/trace.go:171","msg":"trace[1114826582] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"442.028852ms","start":"2026-05-05T18:22:26.289814Z","end":"2026-05-05T18:22:26.731843Z","steps":["trace[1114826582] 'process raft request' (duration: 387.657556ms)","trace[1114826582] 'compare' (duration: 53.677158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:22:26.731984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:22:26.28979Z","time spent":"442.107115ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:22:26.732217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.543851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:22:26.732317Z","caller":"traceutil/trace.go:171","msg":"trace[853363517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1901; }","duration":"368.683705ms","start":"2026-05-05T18:22:26.363623Z","end":"2026-05-05T18:22:26.732307Z","steps":["trace[853363517] 'agreement among raft nodes before linearized reading' (duration: 368.563332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:22:26.732389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:22:26.363601Z","time spent":"368.774186ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:22:26.732674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.529915ms","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-05-05T18:22:26.732715Z","caller":"traceutil/trace.go:171","msg":"trace[21413459] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1901; }","duration":"326.593347ms","start":"2026-05-05T18:22:26.406112Z","end":"2026-05-05T18:22:26.732706Z","steps":["trace[21413459] 'agreement among raft nodes before linearized reading' (duration: 326.509335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:22:26.732745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:22:26.406099Z","time spent":"326.637968ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-05T18:22:26.733158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.612166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T18:22:26.733201Z","caller":"traceutil/trace.go:171","msg":"trace[535097217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1901; }","duration":"203.689718ms","start":"2026-05-05T18:22:26.529501Z","end":"2026-05-05T18:22:26.733191Z","steps":["trace[535097217] 'agreement among raft nodes before linearized reading' (duration: 203.564024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:22:26.733599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.176657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-05T18:22:26.733639Z","caller":"traceutil/trace.go:171","msg":"trace[2133697082] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1901; }","duration":"263.250439ms","start":"2026-05-05T18:22:26.470379Z","end":"2026-05-05T18:22:26.73363Z","steps":["trace[2133697082] 'agreement among raft nodes before linearized reading' (duration: 263.182437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:22:26.733672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.46953ms","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:526"} {"level":"info","ts":"2026-05-05T18:22:26.733752Z","caller":"traceutil/trace.go:171","msg":"trace[666348064] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1901; }","duration":"230.599784ms","start":"2026-05-05T18:22:26.503135Z","end":"2026-05-05T18:22:26.733735Z","steps":["trace[666348064] 'agreement among raft nodes before linearized reading' (duration: 230.334827ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:22:26.918269Z","caller":"traceutil/trace.go:171","msg":"trace[236891383] linearizableReadLoop","detail":"{readStateIndex:1983; appliedIndex:1982; }","duration":"180.348505ms","start":"2026-05-05T18:22:26.737897Z","end":"2026-05-05T18:22:26.918246Z","steps":["trace[236891383] 'read index received' (duration: 106.279539ms)","trace[236891383] 'applied index is now lower than readState.Index' (duration: 74.066816ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:22:26.918285Z","caller":"traceutil/trace.go:171","msg":"trace[973447509] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"180.472138ms","start":"2026-05-05T18:22:26.737781Z","end":"2026-05-05T18:22:26.918253Z","steps":["trace[973447509] 'process raft request' (duration: 106.427833ms)","trace[973447509] 'compare' (duration: 73.758058ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:22:26.91827Z","caller":"traceutil/trace.go:171","msg":"trace[1526711109] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"178.017674ms","start":"2026-05-05T18:22:26.740226Z","end":"2026-05-05T18:22:26.918243Z","steps":["trace[1526711109] 'process raft request' (duration: 177.945852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:22:26.918442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.523729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-05T18:22:26.918526Z","caller":"traceutil/trace.go:171","msg":"trace[1927177518] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1903; }","duration":"180.678322ms","start":"2026-05-05T18:22:26.737831Z","end":"2026-05-05T18:22:26.918509Z","steps":["trace[1927177518] 'agreement among raft nodes before linearized reading' (duration: 180.488098ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:22:27.172899Z","caller":"traceutil/trace.go:171","msg":"trace[35850361] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"248.557368ms","start":"2026-05-05T18:22:26.924324Z","end":"2026-05-05T18:22:27.172881Z","steps":["trace[35850361] 'process raft request' (duration: 248.110816ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:22:27.173214Z","caller":"traceutil/trace.go:171","msg":"trace[628904577] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"248.078025ms","start":"2026-05-05T18:22:26.925101Z","end":"2026-05-05T18:22:27.173179Z","steps":["trace[628904577] 'process raft request' (duration: 247.739937ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:22:27.175698Z","caller":"traceutil/trace.go:171","msg":"trace[924931202] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1985; }","duration":"184.585064ms","start":"2026-05-05T18:22:26.991087Z","end":"2026-05-05T18:22:27.175672Z","steps":["trace[924931202] 'read index received' (duration: 181.36032ms)","trace[924931202] 'applied index is now lower than readState.Index' (duration: 3.223814ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:22:27.175905Z","caller":"traceutil/trace.go:171","msg":"trace[525481305] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"211.748717ms","start":"2026-05-05T18:22:26.964093Z","end":"2026-05-05T18:22:27.175841Z","steps":["trace[525481305] 'process raft request' (duration: 211.473109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:22:27.176372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.274522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-05T18:22:27.176482Z","caller":"traceutil/trace.go:171","msg":"trace[1128105623] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1907; }","duration":"185.378715ms","start":"2026-05-05T18:22:26.991031Z","end":"2026-05-05T18:22:27.17641Z","steps":["trace[1128105623] 'agreement among raft nodes before linearized reading' (duration: 184.714688ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:22:29.067078Z","caller":"traceutil/trace.go:171","msg":"trace[406190263] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"109.628425ms","start":"2026-05-05T18:22:28.957417Z","end":"2026-05-05T18:22:29.067046Z","steps":["trace[406190263] 'process raft request' (duration: 47.827406ms)","trace[406190263] 'compare' (duration: 61.674366ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:23:02.528125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.459634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:23:02.528228Z","caller":"traceutil/trace.go:171","msg":"trace[1086372179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2067; }","duration":"164.612847ms","start":"2026-05-05T18:23:02.363592Z","end":"2026-05-05T18:23:02.528205Z","steps":["trace[1086372179] 'range keys from in-memory index tree' (duration: 164.371861ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:23:03.46379Z","caller":"traceutil/trace.go:171","msg":"trace[2057325184] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2158; }","duration":"133.649047ms","start":"2026-05-05T18:23:03.330118Z","end":"2026-05-05T18:23:03.463767Z","steps":["trace[2057325184] 'read index received' (duration: 133.495373ms)","trace[2057325184] 'applied index is now lower than readState.Index' (duration: 151.883µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:23:03.463841Z","caller":"traceutil/trace.go:171","msg":"trace[894435163] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"168.988731ms","start":"2026-05-05T18:23:03.294827Z","end":"2026-05-05T18:23:03.463816Z","steps":["trace[894435163] 'process raft request' (duration: 168.790246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:23:03.464078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.687331ms","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-05-05T18:23:03.464132Z","caller":"traceutil/trace.go:171","msg":"trace[802528968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2071; }","duration":"132.781263ms","start":"2026-05-05T18:23:03.331337Z","end":"2026-05-05T18:23:03.464119Z","steps":["trace[802528968] 'agreement among raft nodes before linearized reading' (duration: 132.588748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:23:03.464268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.092028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T18:23:03.464358Z","caller":"traceutil/trace.go:171","msg":"trace[1659394291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2071; }","duration":"134.315803ms","start":"2026-05-05T18:23:03.330023Z","end":"2026-05-05T18:23:03.464339Z","steps":["trace[1659394291] 'agreement among raft nodes before linearized reading' (duration: 133.867172ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:23:49.027072Z","caller":"traceutil/trace.go:171","msg":"trace[1616152227] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"102.153427ms","start":"2026-05-05T18:23:48.924899Z","end":"2026-05-05T18:23:49.027052Z","steps":["trace[1616152227] 'process raft request' (duration: 102.028834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:23:57.403203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.378855ms","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:525"} {"level":"info","ts":"2026-05-05T18:23:57.403311Z","caller":"traceutil/trace.go:171","msg":"trace[1528134916] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2344; }","duration":"167.541209ms","start":"2026-05-05T18:23:57.235751Z","end":"2026-05-05T18:23:57.403293Z","steps":["trace[1528134916] 'range keys from in-memory index tree' (duration: 167.14294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:23:57.403788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.315352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:23:57.403893Z","caller":"traceutil/trace.go:171","msg":"trace[943568453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2344; }","duration":"155.421914ms","start":"2026-05-05T18:23:57.248444Z","end":"2026-05-05T18:23:57.403866Z","steps":["trace[943568453] 'range keys from in-memory index tree' (duration: 155.145776ms)"],"step_count":1}