{"level":"warn","ts":"2026-04-21T21:10:18.128487Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T21:10:18.128755Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-04-21T21:10:18.128839Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T21:10:18.128858Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-21T21:10:18.128881Z","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-04-21T21:10:18.129603Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-21T21:10:18.129693Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-04-21T21:10:18.146571Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.554459ms"} {"level":"info","ts":"2026-04-21T21:10:18.157212Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-04-21T21:10:18.157292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T21:10:18.157318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-04-21T21:10:18.157339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T21:10:18.157359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-04-21T21:10:18.157404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-04-21T21:10:18.170349Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T21:10:18.187274Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T21:10:18.198351Z","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-04-21T21:10:18.2019Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T21:10:18.204464Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T21:10:18.205479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-04-21T21:10:18.203796Z","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-04-21T21:10:18.205969Z","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-04-21T21:10:18.205989Z","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-04-21T21:10:18.209056Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-21T21:10:18.20969Z","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-04-21T21:10:18.20991Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T21:10:18.209939Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T21:10:18.210006Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-21T21:10:18.210021Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-21T21:10:18.757861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T21:10:18.75792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T21:10:18.757955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-04-21T21:10:18.757974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-04-21T21:10:18.757997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-21T21:10:18.758013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-04-21T21:10:18.758027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-21T21:10:18.773261Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T21:10:18.775753Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T21:10:18.775799Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T21:10:18.775758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T21:10:18.776056Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T21:10:18.776102Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T21:10:18.778104Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T21:10:18.778316Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T21:10:18.778417Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T21:10:18.778963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T21:10:18.780672Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-04-21T21:10:42.69416Z","caller":"traceutil/trace.go:171","msg":"trace[1634130888] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"571.200745ms","start":"2026-04-21T21:10:42.122921Z","end":"2026-04-21T21:10:42.694121Z","steps":["trace[1634130888] 'process raft request' (duration: 570.947269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:42.694285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.677678ms","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-04-21T21:10:42.694425Z","caller":"traceutil/trace.go:171","msg":"trace[395477198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"363.837731ms","start":"2026-04-21T21:10:42.330541Z","end":"2026-04-21T21:10:42.694379Z","steps":["trace[395477198] 'agreement among raft nodes before linearized reading' (duration: 363.660827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:42.694477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:42.330522Z","time spent":"363.944654ms","remote":"127.0.0.1:34236","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-04-21T21:10:42.694814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:42.122867Z","time spent":"571.384739ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:10:42.694099Z","caller":"traceutil/trace.go:171","msg":"trace[1406377832] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"363.488103ms","start":"2026-04-21T21:10:42.33058Z","end":"2026-04-21T21:10:42.694068Z","steps":["trace[1406377832] 'read index received' (duration: 363.145174ms)","trace[1406377832] 'applied index is now lower than readState.Index' (duration: 341.909µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:10:42.695207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.559786ms","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-04-21T21:10:42.695242Z","caller":"traceutil/trace.go:171","msg":"trace[1036548136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"363.614427ms","start":"2026-04-21T21:10:42.331616Z","end":"2026-04-21T21:10:42.695231Z","steps":["trace[1036548136] 'agreement among raft nodes before linearized reading' (duration: 363.524095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:42.69527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:42.331609Z","time spent":"363.654558ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T21:10:47.713752Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634125580,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:10:48.709106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.452322ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-21T21:10:48.709233Z","caller":"traceutil/trace.go:171","msg":"trace[2133639355] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"1.496301306s","start":"2026-04-21T21:10:47.212913Z","end":"2026-04-21T21:10:48.709214Z","steps":["trace[2133639355] 'read index received' (duration: 528.680319ms)","trace[2133639355] 'applied index is now lower than readState.Index' (duration: 967.619357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:10:48.709354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49642808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:10:48.70939Z","caller":"traceutil/trace.go:171","msg":"trace[410870516] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:471; }","duration":"1.496501292s","start":"2026-04-21T21:10:47.212878Z","end":"2026-04-21T21:10:48.709379Z","steps":["trace[410870516] 'agreement among raft nodes before linearized reading' (duration: 1.49643185s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:48.709426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:47.212861Z","time spent":"1.496556593s","remote":"127.0.0.1:34060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-21T21:10:48.709571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.484617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:10:48.709659Z","caller":"traceutil/trace.go:171","msg":"trace[919972269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"872.62919ms","start":"2026-04-21T21:10:47.837004Z","end":"2026-04-21T21:10:48.709634Z","steps":["trace[919972269] 'agreement among raft nodes before linearized reading' (duration: 872.474746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:48.709704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:47.836987Z","time spent":"872.709522ms","remote":"127.0.0.1:33972","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-04-21T21:10:49.88836Z","caller":"traceutil/trace.go:171","msg":"trace[275623701] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"273.717874ms","start":"2026-04-21T21:10:49.614613Z","end":"2026-04-21T21:10:49.888331Z","steps":["trace[275623701] 'process raft request' (duration: 273.381686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:50.866184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.29096ms","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-04-21T21:10:50.86632Z","caller":"traceutil/trace.go:171","msg":"trace[1986688112] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:535; }","duration":"207.436075ms","start":"2026-04-21T21:10:50.658864Z","end":"2026-04-21T21:10:50.8663Z","steps":["trace[1986688112] 'range keys from in-memory index tree' (duration: 207.27033ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:10:50.866603Z","caller":"traceutil/trace.go:171","msg":"trace[548567310] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.225368281s","start":"2026-04-21T21:10:49.641184Z","end":"2026-04-21T21:10:50.866553Z","steps":["trace[548567310] 'process raft request' (duration: 1.221968266s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:50.866739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:49.641158Z","time spent":"1.225512274s","remote":"127.0.0.1:34014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:10:50.86736Z","caller":"traceutil/trace.go:171","msg":"trace[1667714599] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"1.031068779s","start":"2026-04-21T21:10:49.836265Z","end":"2026-04-21T21:10:50.867334Z","steps":["trace[1667714599] 'read index received' (duration: 52.532293ms)","trace[1667714599] 'applied index is now lower than readState.Index' (duration: 978.535496ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:10:50.867566Z","caller":"traceutil/trace.go:171","msg":"trace[1955224551] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.061824617s","start":"2026-04-21T21:10:49.805698Z","end":"2026-04-21T21:10:50.867523Z","steps":["trace[1955224551] 'process raft request' (duration: 1.061170291s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:50.867657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031387037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:10:50.867692Z","caller":"traceutil/trace.go:171","msg":"trace[303914181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"1.031421897s","start":"2026-04-21T21:10:49.83626Z","end":"2026-04-21T21:10:50.867682Z","steps":["trace[303914181] 'agreement among raft nodes before linearized reading' (duration: 1.031338775s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:50.867713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:49.83622Z","time spent":"1.031489249s","remote":"127.0.0.1:33972","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-04-21T21:10:50.86772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:49.805679Z","time spent":"1.06197515s","remote":"127.0.0.1:34014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T21:10:50.878778Z","caller":"traceutil/trace.go:171","msg":"trace[164658673] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"968.157152ms","start":"2026-04-21T21:10:49.910607Z","end":"2026-04-21T21:10:50.878764Z","steps":["trace[164658673] 'process raft request' (duration: 967.903526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:50.878866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:49.910588Z","time spent":"968.227653ms","remote":"127.0.0.1:34014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:10:50.879147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.152752ms","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-04-21T21:10:50.879169Z","caller":"traceutil/trace.go:171","msg":"trace[52436175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"143.212014ms","start":"2026-04-21T21:10:50.73595Z","end":"2026-04-21T21:10:50.879162Z","steps":["trace[52436175] 'agreement among raft nodes before linearized reading' (duration: 143.149322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:50.879267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.372047ms","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-04-21T21:10:50.879283Z","caller":"traceutil/trace.go:171","msg":"trace[1116223082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"143.410238ms","start":"2026-04-21T21:10:50.735868Z","end":"2026-04-21T21:10:50.879278Z","steps":["trace[1116223082] 'agreement among raft nodes before linearized reading' (duration: 143.366637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:50.879397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.000788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:10:50.879413Z","caller":"traceutil/trace.go:171","msg":"trace[819943904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"156.04922ms","start":"2026-04-21T21:10:50.723359Z","end":"2026-04-21T21:10:50.879408Z","steps":["trace[819943904] 'agreement among raft nodes before linearized reading' (duration: 156.000948ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:10:53.36295Z","caller":"traceutil/trace.go:171","msg":"trace[162260855] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"305.927207ms","start":"2026-04-21T21:10:53.056998Z","end":"2026-04-21T21:10:53.362925Z","steps":["trace[162260855] 'process raft request' (duration: 305.580439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:53.363139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:53.056979Z","time spent":"306.085311ms","remote":"127.0.0.1:34060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T21:10:55.240753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.755086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:10:55.240913Z","caller":"traceutil/trace.go:171","msg":"trace[224055786] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"294.841065ms","start":"2026-04-21T21:10:54.946055Z","end":"2026-04-21T21:10:55.240896Z","steps":["trace[224055786] 'process raft request' (duration: 294.790343ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:10:55.241029Z","caller":"traceutil/trace.go:171","msg":"trace[1108014555] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"294.990918ms","start":"2026-04-21T21:10:54.946028Z","end":"2026-04-21T21:10:55.241019Z","steps":["trace[1108014555] 'process raft request' (duration: 161.879393ms)","trace[1108014555] 'compare' (duration: 132.665184ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:10:56.061673Z","caller":"traceutil/trace.go:171","msg":"trace[378764525] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"380.463062ms","start":"2026-04-21T21:10:55.681142Z","end":"2026-04-21T21:10:56.061605Z","steps":["trace[378764525] 'read index received' (duration: 378.422711ms)","trace[378764525] 'applied index is now lower than readState.Index' (duration: 2.038271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:10:56.062326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.16851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-975pp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-21T21:10:56.062454Z","caller":"traceutil/trace.go:171","msg":"trace[977997603] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"802.487268ms","start":"2026-04-21T21:10:55.25994Z","end":"2026-04-21T21:10:56.062427Z","steps":["trace[977997603] 'process raft request' (duration: 799.7524ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:10:56.062517Z","caller":"traceutil/trace.go:171","msg":"trace[246280822] range","detail":"{range_begin:/registry/pods/kube-system/cilium-975pp; range_end:; response_count:1; response_revision:579; }","duration":"381.344314ms","start":"2026-04-21T21:10:55.681124Z","end":"2026-04-21T21:10:56.062468Z","steps":["trace[246280822] 'agreement among raft nodes before linearized reading' (duration: 380.954684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:56.062594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:55.681052Z","time spent":"381.511028ms","remote":"127.0.0.1:34162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-975pp\" "} {"level":"warn","ts":"2026-04-21T21:10:56.062626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.665427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:10:56.062719Z","caller":"traceutil/trace.go:171","msg":"trace[637413901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"226.79081ms","start":"2026-04-21T21:10:55.835903Z","end":"2026-04-21T21:10:56.062694Z","steps":["trace[637413901] 'agreement among raft nodes before linearized reading' (duration: 226.576985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:56.062858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:55.259914Z","time spent":"802.61667ms","remote":"127.0.0.1:34060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:10:56.181161Z","caller":"traceutil/trace.go:171","msg":"trace[1576819301] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"102.136263ms","start":"2026-04-21T21:10:56.078999Z","end":"2026-04-21T21:10:56.181135Z","steps":["trace[1576819301] 'process raft request' (duration: 78.073781ms)","trace[1576819301] 'compare' (duration: 23.637392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:10:57.336969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634125772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:10:57.837605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634125772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:10:58.300364Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.717423217s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T21:10:58.304289Z","caller":"traceutil/trace.go:171","msg":"trace[1383659249] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.722713447s","start":"2026-04-21T21:10:56.581542Z","end":"2026-04-21T21:10:58.304256Z","steps":["trace[1383659249] 'process raft request' (duration: 1.719261911s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:58.304452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:56.581513Z","time spent":"1.722849781s","remote":"127.0.0.1:34060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:10:58.336993Z","caller":"traceutil/trace.go:171","msg":"trace[103037810] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"1.500691614s","start":"2026-04-21T21:10:56.836278Z","end":"2026-04-21T21:10:58.33697Z","steps":["trace[103037810] 'read index received' (duration: 1.464384881s)","trace[103037810] 'applied index is now lower than readState.Index' (duration: 36.305273ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:10:58.337142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500857088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:10:58.337183Z","caller":"traceutil/trace.go:171","msg":"trace[360272458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"1.50093708s","start":"2026-04-21T21:10:56.836235Z","end":"2026-04-21T21:10:58.337172Z","steps":["trace[360272458] 'agreement among raft nodes before linearized reading' (duration: 1.500864699s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:58.337263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:56.836216Z","time spent":"1.501039422s","remote":"127.0.0.1:33972","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-04-21T21:10:58.337453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09205346s","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-04-21T21:10:58.337481Z","caller":"traceutil/trace.go:171","msg":"trace[1672520875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.092113852s","start":"2026-04-21T21:10:57.245359Z","end":"2026-04-21T21:10:58.337473Z","steps":["trace[1672520875] 'agreement among raft nodes before linearized reading' (duration: 1.092010859s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:58.337499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:57.245342Z","time spent":"1.092152972s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T21:10:58.337607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383214544s","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-04-21T21:10:58.337708Z","caller":"traceutil/trace.go:171","msg":"trace[660755623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.383378578s","start":"2026-04-21T21:10:56.95432Z","end":"2026-04-21T21:10:58.337699Z","steps":["trace[660755623] 'agreement among raft nodes before linearized reading' (duration: 1.383032119s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:58.337825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383296666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:10:58.337851Z","caller":"traceutil/trace.go:171","msg":"trace[1598300797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"1.383361608s","start":"2026-04-21T21:10:56.954483Z","end":"2026-04-21T21:10:58.337844Z","steps":["trace[1598300797] 'agreement among raft nodes before linearized reading' (duration: 1.383304696s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:58.337869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:56.954453Z","time spent":"1.383412009s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T21:10:58.337942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:56.9543Z","time spent":"1.383512322s","remote":"127.0.0.1:34236","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-04-21T21:10:58.337762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093008195s","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-04-21T21:10:58.338224Z","caller":"traceutil/trace.go:171","msg":"trace[1123621712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"1.093476206s","start":"2026-04-21T21:10:57.244712Z","end":"2026-04-21T21:10:58.338189Z","steps":["trace[1123621712] 'agreement among raft nodes before linearized reading' (duration: 1.093001864s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:10:58.338332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:10:57.244691Z","time spent":"1.09360392s","remote":"127.0.0.1:34236","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-04-21T21:11:02.308873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:01.874312Z","time spent":"434.558673ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-21T21:11:03.108929Z","caller":"traceutil/trace.go:171","msg":"trace[1915811744] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"251.394286ms","start":"2026-04-21T21:11:02.857514Z","end":"2026-04-21T21:11:03.108909Z","steps":["trace[1915811744] 'process raft request' (duration: 251.275873ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.594807Z","caller":"traceutil/trace.go:171","msg":"trace[298266923] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"150.109634ms","start":"2026-04-21T21:11:10.444675Z","end":"2026-04-21T21:11:10.594784Z","steps":["trace[298266923] 'process raft request' (duration: 148.845752ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.595038Z","caller":"traceutil/trace.go:171","msg":"trace[623096767] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"150.252748ms","start":"2026-04-21T21:11:10.444762Z","end":"2026-04-21T21:11:10.595015Z","steps":["trace[623096767] 'process raft request' (duration: 149.989791ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.59526Z","caller":"traceutil/trace.go:171","msg":"trace[1661491873] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"150.441081ms","start":"2026-04-21T21:11:10.444811Z","end":"2026-04-21T21:11:10.595252Z","steps":["trace[1661491873] 'process raft request' (duration: 150.150174ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.595506Z","caller":"traceutil/trace.go:171","msg":"trace[27104505] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"150.043901ms","start":"2026-04-21T21:11:10.445454Z","end":"2026-04-21T21:11:10.595498Z","steps":["trace[27104505] 'process raft request' (duration: 149.711553ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.595838Z","caller":"traceutil/trace.go:171","msg":"trace[405628950] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"148.415272ms","start":"2026-04-21T21:11:10.447395Z","end":"2026-04-21T21:11:10.595811Z","steps":["trace[405628950] 'process raft request' (duration: 147.999212ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.597313Z","caller":"traceutil/trace.go:171","msg":"trace[1813360690] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"142.744222ms","start":"2026-04-21T21:11:10.454551Z","end":"2026-04-21T21:11:10.597295Z","steps":["trace[1813360690] 'process raft request' (duration: 140.966748ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.770434Z","caller":"traceutil/trace.go:171","msg":"trace[1357113838] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"155.583378ms","start":"2026-04-21T21:11:10.61483Z","end":"2026-04-21T21:11:10.770413Z","steps":["trace[1357113838] 'process raft request' (duration: 152.301756ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.771017Z","caller":"traceutil/trace.go:171","msg":"trace[2062363283] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"153.578389ms","start":"2026-04-21T21:11:10.61701Z","end":"2026-04-21T21:11:10.770589Z","steps":["trace[2062363283] 'process raft request' (duration: 153.442886ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:10.959321Z","caller":"traceutil/trace.go:171","msg":"trace[1705421398] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:743; }","duration":"276.173106ms","start":"2026-04-21T21:11:10.683112Z","end":"2026-04-21T21:11:10.959285Z","steps":["trace[1705421398] 'read index received' (duration: 84.030318ms)","trace[1705421398] 'applied index is now lower than readState.Index' (duration: 192.141468ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:11:10.959399Z","caller":"traceutil/trace.go:171","msg":"trace[1579790429] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"310.668275ms","start":"2026-04-21T21:11:10.648693Z","end":"2026-04-21T21:11:10.959361Z","steps":["trace[1579790429] 'process raft request' (duration: 304.838231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:10.959534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.71531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:11:10.959576Z","caller":"traceutil/trace.go:171","msg":"trace[189650559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"122.827543ms","start":"2026-04-21T21:11:10.836738Z","end":"2026-04-21T21:11:10.959566Z","steps":["trace[189650559] 'agreement among raft nodes before linearized reading' (duration: 122.72816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:10.959589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:10.648666Z","time spent":"310.830908ms","remote":"127.0.0.1:34146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4528,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:11:10.959983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.834252ms","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-04-21T21:11:10.961286Z","caller":"traceutil/trace.go:171","msg":"trace[517681141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:719; }","duration":"276.985605ms","start":"2026-04-21T21:11:10.683066Z","end":"2026-04-21T21:11:10.960052Z","steps":["trace[517681141] 'agreement among raft nodes before linearized reading' (duration: 276.666508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:16.305196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.517795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dzzn9\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-21T21:11:16.305297Z","caller":"traceutil/trace.go:171","msg":"trace[533095030] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dzzn9; range_end:; response_count:1; response_revision:753; }","duration":"511.683179ms","start":"2026-04-21T21:11:15.793591Z","end":"2026-04-21T21:11:16.305274Z","steps":["trace[533095030] 'range keys from in-memory index tree' (duration: 511.323061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:16.305345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:15.793572Z","time spent":"511.762881ms","remote":"127.0.0.1:34162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dzzn9\" "} {"level":"info","ts":"2026-04-21T21:11:30.809704Z","caller":"traceutil/trace.go:171","msg":"trace[1124509594] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"237.20359ms","start":"2026-04-21T21:11:30.572479Z","end":"2026-04-21T21:11:30.809683Z","steps":["trace[1124509594] 'read index received' (duration: 236.996856ms)","trace[1124509594] 'applied index is now lower than readState.Index' (duration: 205.754µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:11:30.809831Z","caller":"traceutil/trace.go:171","msg":"trace[660130619] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"535.162652ms","start":"2026-04-21T21:11:30.274634Z","end":"2026-04-21T21:11:30.809797Z","steps":["trace[660130619] 'process raft request' (duration: 534.902676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:30.809939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.445206ms","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-04-21T21:11:30.809982Z","caller":"traceutil/trace.go:171","msg":"trace[196558249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"237.509427ms","start":"2026-04-21T21:11:30.572462Z","end":"2026-04-21T21:11:30.809972Z","steps":["trace[196558249] 'agreement among raft nodes before linearized reading' (duration: 237.344913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:30.810013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:30.274606Z","time spent":"535.314216ms","remote":"127.0.0.1:34162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5468,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:11:30.810076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.968149ms","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-04-21T21:11:30.810161Z","caller":"traceutil/trace.go:171","msg":"trace[63410913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"126.098092ms","start":"2026-04-21T21:11:30.684041Z","end":"2026-04-21T21:11:30.810139Z","steps":["trace[63410913] 'agreement among raft nodes before linearized reading' (duration: 125.874147ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:31.106927Z","caller":"traceutil/trace.go:171","msg":"trace[1949600489] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"291.164492ms","start":"2026-04-21T21:11:30.815726Z","end":"2026-04-21T21:11:31.10689Z","steps":["trace[1949600489] 'process raft request' (duration: 290.23983ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:31.107008Z","caller":"traceutil/trace.go:171","msg":"trace[545842738] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"291.137711ms","start":"2026-04-21T21:11:30.815849Z","end":"2026-04-21T21:11:31.106986Z","steps":["trace[545842738] 'process raft request' (duration: 290.945027ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:31.107133Z","caller":"traceutil/trace.go:171","msg":"trace[1488228402] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"288.427178ms","start":"2026-04-21T21:11:30.818685Z","end":"2026-04-21T21:11:31.107113Z","steps":["trace[1488228402] 'process raft request' (duration: 288.366657ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:31.10716Z","caller":"traceutil/trace.go:171","msg":"trace[1408718455] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"290.684471ms","start":"2026-04-21T21:11:30.816457Z","end":"2026-04-21T21:11:31.107141Z","steps":["trace[1408718455] 'process raft request' (duration: 290.489586ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:31.107346Z","caller":"traceutil/trace.go:171","msg":"trace[74352317] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"290.999808ms","start":"2026-04-21T21:11:30.81633Z","end":"2026-04-21T21:11:31.10733Z","steps":["trace[74352317] 'process raft request' (duration: 290.548558ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:31.107411Z","caller":"traceutil/trace.go:171","msg":"trace[1724137386] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"289.871672ms","start":"2026-04-21T21:11:30.817522Z","end":"2026-04-21T21:11:31.107393Z","steps":["trace[1724137386] 'process raft request' (duration: 289.474092ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:31.524551Z","caller":"traceutil/trace.go:171","msg":"trace[563903993] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:916; }","duration":"542.034014ms","start":"2026-04-21T21:11:30.982494Z","end":"2026-04-21T21:11:31.524528Z","steps":["trace[563903993] 'read index received' (duration: 123.416529ms)","trace[563903993] 'applied index is now lower than readState.Index' (duration: 418.616745ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:11:31.524808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.30537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T21:11:31.52484Z","caller":"traceutil/trace.go:171","msg":"trace[1507008485] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:891; }","duration":"542.410112ms","start":"2026-04-21T21:11:30.982422Z","end":"2026-04-21T21:11:31.524832Z","steps":["trace[1507008485] 'agreement among raft nodes before linearized reading' (duration: 542.31083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:31.524863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:30.982367Z","time spent":"542.490544ms","remote":"127.0.0.1:34318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-21T21:11:31.524863Z","caller":"traceutil/trace.go:171","msg":"trace[283509079] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"575.316258ms","start":"2026-04-21T21:11:30.949467Z","end":"2026-04-21T21:11:31.524783Z","steps":["trace[283509079] 'process raft request' (duration: 574.97364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:31.525055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:30.949442Z","time spent":"575.504962ms","remote":"127.0.0.1:34236","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-04-21T21:11:31.53782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.221294ms","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-04-21T21:11:31.537869Z","caller":"traceutil/trace.go:171","msg":"trace[127919013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"451.324586ms","start":"2026-04-21T21:11:31.086536Z","end":"2026-04-21T21:11:31.53786Z","steps":["trace[127919013] 'agreement among raft nodes before linearized reading' (duration: 451.176193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:31.537897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:31.086519Z","time spent":"451.370287ms","remote":"127.0.0.1:34236","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":"info","ts":"2026-04-21T21:11:31.53781Z","caller":"traceutil/trace.go:171","msg":"trace[1521115342] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"420.136891ms","start":"2026-04-21T21:11:31.11764Z","end":"2026-04-21T21:11:31.537777Z","steps":["trace[1521115342] 'process raft request' (duration: 419.978998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:31.537998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:31.117626Z","time spent":"420.317845ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:11:51.152126Z","caller":"traceutil/trace.go:171","msg":"trace[29664335] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"160.385341ms","start":"2026-04-21T21:11:50.99172Z","end":"2026-04-21T21:11:51.152105Z","steps":["trace[29664335] 'process raft request' (duration: 160.263898ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:51.429605Z","caller":"traceutil/trace.go:171","msg":"trace[262014716] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"232.357406ms","start":"2026-04-21T21:11:51.196688Z","end":"2026-04-21T21:11:51.429046Z","steps":["trace[262014716] 'read index received' (duration: 205.625027ms)","trace[262014716] 'applied index is now lower than readState.Index' (duration: 26.731559ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:11:51.429672Z","caller":"traceutil/trace.go:171","msg":"trace[475731344] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"235.793038ms","start":"2026-04-21T21:11:51.193834Z","end":"2026-04-21T21:11:51.429627Z","steps":["trace[475731344] 'process raft request' (duration: 208.463203ms)","trace[475731344] 'compare' (duration: 26.634448ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:11:51.430134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.419032ms","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-04-21T21:11:51.430284Z","caller":"traceutil/trace.go:171","msg":"trace[391260070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"233.621426ms","start":"2026-04-21T21:11:51.196631Z","end":"2026-04-21T21:11:51.430253Z","steps":["trace[391260070] 'agreement among raft nodes before linearized reading' (duration: 233.099474ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:51.461388Z","caller":"traceutil/trace.go:171","msg":"trace[1611202560] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"263.760877ms","start":"2026-04-21T21:11:51.197601Z","end":"2026-04-21T21:11:51.461362Z","steps":["trace[1611202560] 'process raft request' (duration: 263.543261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:52.145664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634126623,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:11:52.646809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634126623,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:11:52.704255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167292949s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:11:52.70437Z","caller":"traceutil/trace.go:171","msg":"trace[1987122786] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.267291846s","start":"2026-04-21T21:11:51.43706Z","end":"2026-04-21T21:11:52.704351Z","steps":["trace[1987122786] 'process raft request' (duration: 99.830153ms)","trace[1987122786] 'compare' (duration: 1.167049484s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:11:52.704432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:51.437034Z","time spent":"1.267367668s","remote":"127.0.0.1:34236","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-04-21T21:11:53.147523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634126623,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:11:53.648524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634126623,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:11:53.837346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000095734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-21T21:11:53.837435Z","caller":"traceutil/trace.go:171","msg":"trace[1511707074] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000231448s","start":"2026-04-21T21:11:51.837181Z","end":"2026-04-21T21:11:53.837413Z","steps":["trace[1511707074] 'agreement among raft nodes before linearized reading' (duration: 2.000120665s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:53.837486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:51.837165Z","time spent":"2.00031133s","remote":"127.0.0.1:33972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T21:11:53.994024Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.39576311s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T21:11:53.995463Z","caller":"traceutil/trace.go:171","msg":"trace[638300325] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"2.397365918s","start":"2026-04-21T21:11:51.598071Z","end":"2026-04-21T21:11:53.995436Z","steps":["trace[638300325] 'process raft request' (duration: 2.397190063s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:53.995683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:51.598044Z","time spent":"2.397540431s","remote":"127.0.0.1:34236","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-04-21T21:11:54.035209Z","caller":"traceutil/trace.go:171","msg":"trace[899647698] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1003; }","duration":"2.389812849s","start":"2026-04-21T21:11:51.645368Z","end":"2026-04-21T21:11:54.035181Z","steps":["trace[899647698] 'read index received' (duration: 2.349993371s)","trace[899647698] 'applied index is now lower than readState.Index' (duration: 39.818078ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:11:54.035349Z","caller":"traceutil/trace.go:171","msg":"trace[1080285549] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.864346357s","start":"2026-04-21T21:11:52.170991Z","end":"2026-04-21T21:11:54.035337Z","steps":["trace[1080285549] 'process raft request' (duration: 1.863988118s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:54.035496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:52.170964Z","time spent":"1.864442428s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:11:54.035747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.757521ms","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-04-21T21:11:54.035793Z","caller":"traceutil/trace.go:171","msg":"trace[599365227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"570.844453ms","start":"2026-04-21T21:11:53.464935Z","end":"2026-04-21T21:11:54.03578Z","steps":["trace[599365227] 'agreement among raft nodes before linearized reading' (duration: 570.69976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:54.03582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:53.464914Z","time spent":"570.900395ms","remote":"127.0.0.1:34236","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-04-21T21:11:54.035812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.160417963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T21:11:54.035935Z","caller":"traceutil/trace.go:171","msg":"trace[1072601969] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:968; }","duration":"2.160570676s","start":"2026-04-21T21:11:51.875313Z","end":"2026-04-21T21:11:54.035883Z","steps":["trace[1072601969] 'agreement among raft nodes before linearized reading' (duration: 2.16030379s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:54.035978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.70863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T21:11:54.035985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.390618807s","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":"warn","ts":"2026-04-21T21:11:54.035984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:51.875291Z","time spent":"2.160684828s","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-04-21T21:11:54.036Z","caller":"traceutil/trace.go:171","msg":"trace[562559721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"197.763581ms","start":"2026-04-21T21:11:53.83823Z","end":"2026-04-21T21:11:54.035994Z","steps":["trace[562559721] 'agreement among raft nodes before linearized reading' (duration: 197.7292ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:54.036019Z","caller":"traceutil/trace.go:171","msg":"trace[750213697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"2.390684309s","start":"2026-04-21T21:11:51.645324Z","end":"2026-04-21T21:11:54.036009Z","steps":["trace[750213697] 'agreement among raft nodes before linearized reading' (duration: 2.390578967s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:54.036048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:51.645307Z","time spent":"2.39073491s","remote":"127.0.0.1:34236","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-04-21T21:11:54.036252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139884563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:11:54.036281Z","caller":"traceutil/trace.go:171","msg":"trace[267475143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"1.139941064s","start":"2026-04-21T21:11:52.896331Z","end":"2026-04-21T21:11:54.036272Z","steps":["trace[267475143] 'agreement among raft nodes before linearized reading' (duration: 1.139897713s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:54.036285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.889033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-21T21:11:54.036309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:52.896312Z","time spent":"1.139991125s","remote":"127.0.0.1:33972","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-04-21T21:11:54.036312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.866128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:11:54.03632Z","caller":"traceutil/trace.go:171","msg":"trace[1605130053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"598.948865ms","start":"2026-04-21T21:11:53.437361Z","end":"2026-04-21T21:11:54.036309Z","steps":["trace[1605130053] 'agreement among raft nodes before linearized reading' (duration: 598.853083ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:54.036339Z","caller":"traceutil/trace.go:171","msg":"trace[863090436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"880.94263ms","start":"2026-04-21T21:11:53.155388Z","end":"2026-04-21T21:11:54.036331Z","steps":["trace[863090436] 'agreement among raft nodes before linearized reading' (duration: 880.875698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:54.03635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:53.437345Z","time spent":"598.999126ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T21:11:54.036359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:53.155365Z","time spent":"880.990951ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T21:11:58.275465Z","caller":"traceutil/trace.go:171","msg":"trace[1507962118] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"189.557927ms","start":"2026-04-21T21:11:58.085883Z","end":"2026-04-21T21:11:58.275441Z","steps":["trace[1507962118] 'process raft request' (duration: 189.404273ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:58.279176Z","caller":"traceutil/trace.go:171","msg":"trace[1357380927] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"192.225881ms","start":"2026-04-21T21:11:58.086938Z","end":"2026-04-21T21:11:58.279164Z","steps":["trace[1357380927] 'process raft request' (duration: 192.19918ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:58.279384Z","caller":"traceutil/trace.go:171","msg":"trace[62556130] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"193.024789ms","start":"2026-04-21T21:11:58.086353Z","end":"2026-04-21T21:11:58.279377Z","steps":["trace[62556130] 'process raft request' (duration: 192.708432ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:58.279464Z","caller":"traceutil/trace.go:171","msg":"trace[1380287308] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"192.962058ms","start":"2026-04-21T21:11:58.086497Z","end":"2026-04-21T21:11:58.279459Z","steps":["trace[1380287308] 'process raft request' (duration: 192.6179ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:04.213541Z","caller":"traceutil/trace.go:171","msg":"trace[1433130805] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"126.517551ms","start":"2026-04-21T21:12:04.086995Z","end":"2026-04-21T21:12:04.213513Z","steps":["trace[1433130805] 'process raft request' (duration: 114.814155ms)","trace[1433130805] 'compare' (duration: 11.590613ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:04.430171Z","caller":"traceutil/trace.go:171","msg":"trace[1967140725] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"123.320816ms","start":"2026-04-21T21:12:04.306826Z","end":"2026-04-21T21:12:04.430147Z","steps":["trace[1967140725] 'process raft request' (duration: 123.109761ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:04.430422Z","caller":"traceutil/trace.go:171","msg":"trace[1336875593] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"122.509896ms","start":"2026-04-21T21:12:04.307826Z","end":"2026-04-21T21:12:04.430336Z","steps":["trace[1336875593] 'process raft request' (duration: 122.22213ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:04.430324Z","caller":"traceutil/trace.go:171","msg":"trace[641834559] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"122.379623ms","start":"2026-04-21T21:12:04.307857Z","end":"2026-04-21T21:12:04.430237Z","steps":["trace[641834559] 'process raft request' (duration: 122.22549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:06.516258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.359571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:12:06.516325Z","caller":"traceutil/trace.go:171","msg":"trace[1629172497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"113.470624ms","start":"2026-04-21T21:12:06.402841Z","end":"2026-04-21T21:12:06.516312Z","steps":["trace[1629172497] 'range keys from in-memory index tree' (duration: 113.235048ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:06.860827Z","caller":"traceutil/trace.go:171","msg":"trace[1549241670] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"340.374841ms","start":"2026-04-21T21:12:06.520423Z","end":"2026-04-21T21:12:06.860798Z","steps":["trace[1549241670] 'process raft request' (duration: 340.290569ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:06.860857Z","caller":"traceutil/trace.go:171","msg":"trace[336128894] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"340.514825ms","start":"2026-04-21T21:12:06.520318Z","end":"2026-04-21T21:12:06.860833Z","steps":["trace[336128894] 'process raft request' (duration: 340.277429ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:06.860869Z","caller":"traceutil/trace.go:171","msg":"trace[1008869365] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"336.736596ms","start":"2026-04-21T21:12:06.524102Z","end":"2026-04-21T21:12:06.860838Z","steps":["trace[1008869365] 'process raft request' (duration: 336.636773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:06.860963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:06.520389Z","time spent":"340.502024ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:12:06.860978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:06.520309Z","time spent":"340.603216ms","remote":"127.0.0.1:34236","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-04-21T21:12:06.861018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:06.524076Z","time spent":"336.859938ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:12:14.523112Z","caller":"traceutil/trace.go:171","msg":"trace[395110257] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"233.982904ms","start":"2026-04-21T21:12:14.289101Z","end":"2026-04-21T21:12:14.523084Z","steps":["trace[395110257] 'process raft request' (duration: 233.861681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:15.335308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634126806,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T21:12:15.433925Z","caller":"traceutil/trace.go:171","msg":"trace[645272334] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"826.56509ms","start":"2026-04-21T21:12:14.607335Z","end":"2026-04-21T21:12:15.4339Z","steps":["trace[645272334] 'process raft request' (duration: 826.456897ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:15.434053Z","caller":"traceutil/trace.go:171","msg":"trace[683593619] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1079; }","duration":"599.722783ms","start":"2026-04-21T21:12:14.834277Z","end":"2026-04-21T21:12:15.434Z","steps":["trace[683593619] 'read index received' (duration: 599.703883ms)","trace[683593619] 'applied index is now lower than readState.Index' (duration: 15.7µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:15.434096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:14.607318Z","time spent":"826.672582ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:12:15.434228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.933529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:15.434299Z","caller":"traceutil/trace.go:171","msg":"trace[910557625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"600.069672ms","start":"2026-04-21T21:12:14.834211Z","end":"2026-04-21T21:12:15.434281Z","steps":["trace[910557625] 'agreement among raft nodes before linearized reading' (duration: 599.933848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:15.434332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:14.83419Z","time spent":"600.135704ms","remote":"127.0.0.1:33972","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-04-21T21:12:15.797187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.463176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:12:15.797359Z","caller":"traceutil/trace.go:171","msg":"trace[202327731] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"363.19405ms","start":"2026-04-21T21:12:15.434147Z","end":"2026-04-21T21:12:15.797342Z","steps":["trace[202327731] 'read index received' (duration: 198.387096ms)","trace[202327731] 'applied index is now lower than readState.Index' (duration: 164.805084ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:15.797581Z","caller":"traceutil/trace.go:171","msg":"trace[156159471] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"432.634305ms","start":"2026-04-21T21:12:15.364934Z","end":"2026-04-21T21:12:15.797568Z","steps":["trace[156159471] 'process raft request' (duration: 267.714388ms)","trace[156159471] 'compare' (duration: 164.111278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:15.797644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:15.364906Z","time spent":"432.703777ms","remote":"127.0.0.1:34236","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-04-21T21:12:15.797854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.767587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T21:12:15.797889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.345334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:12:15.797921Z","caller":"traceutil/trace.go:171","msg":"trace[26355724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"902.377835ms","start":"2026-04-21T21:12:14.895534Z","end":"2026-04-21T21:12:15.797912Z","steps":["trace[26355724] 'agreement among raft nodes before linearized reading' (duration: 902.260212ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:15.797928Z","caller":"traceutil/trace.go:171","msg":"trace[898959493] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1038; }","duration":"684.855069ms","start":"2026-04-21T21:12:15.113053Z","end":"2026-04-21T21:12:15.797908Z","steps":["trace[898959493] 'agreement among raft nodes before linearized reading' (duration: 684.732586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:15.797948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:14.895495Z","time spent":"902.446607ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T21:12:15.797953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.577762ms","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-04-21T21:12:15.79797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:15.113021Z","time spent":"684.938271ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-04-21T21:12:15.798003Z","caller":"traceutil/trace.go:171","msg":"trace[1210487848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1038; }","duration":"897.655814ms","start":"2026-04-21T21:12:14.900323Z","end":"2026-04-21T21:12:15.797978Z","steps":["trace[1210487848] 'agreement among raft nodes before linearized reading' (duration: 897.583132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:15.79804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:14.900299Z","time spent":"897.734096ms","remote":"127.0.0.1:34236","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-04-21T21:12:15.798109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.635236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:15.798138Z","caller":"traceutil/trace.go:171","msg":"trace[98983329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"362.704117ms","start":"2026-04-21T21:12:15.435426Z","end":"2026-04-21T21:12:15.79813Z","steps":["trace[98983329] 'agreement among raft nodes before linearized reading' (duration: 362.660906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:15.798162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:15.435406Z","time spent":"362.751668ms","remote":"127.0.0.1:33976","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-04-21T21:12:15.79818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.561138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T21:12:15.798211Z","caller":"traceutil/trace.go:171","msg":"trace[282604575] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1038; }","duration":"624.63184ms","start":"2026-04-21T21:12:15.17357Z","end":"2026-04-21T21:12:15.798202Z","steps":["trace[282604575] 'agreement among raft nodes before linearized reading' (duration: 624.575239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:15.798232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.262233ms","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-04-21T21:12:15.79824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:15.173547Z","time spent":"624.685712ms","remote":"127.0.0.1:34290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-21T21:12:15.798262Z","caller":"traceutil/trace.go:171","msg":"trace[1317342937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"902.292674ms","start":"2026-04-21T21:12:14.895962Z","end":"2026-04-21T21:12:15.798255Z","steps":["trace[1317342937] 'agreement among raft nodes before linearized reading' (duration: 902.218832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:15.798285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:14.895931Z","time spent":"902.348565ms","remote":"127.0.0.1:34236","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-04-21T21:12:15.797863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.1582ms","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-04-21T21:12:15.79843Z","caller":"traceutil/trace.go:171","msg":"trace[554049223] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1038; }","duration":"902.742364ms","start":"2026-04-21T21:12:14.895633Z","end":"2026-04-21T21:12:15.798375Z","steps":["trace[554049223] 'agreement among raft nodes before linearized reading' (duration: 902.056158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:15.798469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:14.8956Z","time spent":"902.857058ms","remote":"127.0.0.1:34236","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-04-21T21:12:16.04494Z","caller":"traceutil/trace.go:171","msg":"trace[420532146] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"241.982582ms","start":"2026-04-21T21:12:15.802927Z","end":"2026-04-21T21:12:16.04491Z","steps":["trace[420532146] 'process raft request' (duration: 174.773908ms)","trace[420532146] 'compare' (duration: 67.087141ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:16.114622Z","caller":"traceutil/trace.go:171","msg":"trace[131333558] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"311.351307ms","start":"2026-04-21T21:12:15.803228Z","end":"2026-04-21T21:12:16.114579Z","steps":["trace[131333558] 'process raft request' (duration: 311.243234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:16.114937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:15.803218Z","time spent":"311.529272ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:12:16.115075Z","caller":"traceutil/trace.go:171","msg":"trace[2020892663] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"311.384539ms","start":"2026-04-21T21:12:15.803667Z","end":"2026-04-21T21:12:16.115051Z","steps":["trace[2020892663] 'process raft request' (duration: 310.852246ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:16.115111Z","caller":"traceutil/trace.go:171","msg":"trace[97394217] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"312.091325ms","start":"2026-04-21T21:12:15.802979Z","end":"2026-04-21T21:12:16.11507Z","steps":["trace[97394217] 'process raft request' (duration: 311.228024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:16.115204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:15.803658Z","time spent":"311.45814ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:12:16.114914Z","caller":"traceutil/trace.go:171","msg":"trace[1256030259] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1080; }","duration":"278.784809ms","start":"2026-04-21T21:12:15.836102Z","end":"2026-04-21T21:12:16.114887Z","steps":["trace[1256030259] 'read index received' (duration: 141.604066ms)","trace[1256030259] 'applied index is now lower than readState.Index' (duration: 137.179673ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:16.11532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:15.802954Z","time spent":"312.241479ms","remote":"127.0.0.1:34236","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-04-21T21:12:16.11534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.219661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:16.115464Z","caller":"traceutil/trace.go:171","msg":"trace[2061415227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"279.395215ms","start":"2026-04-21T21:12:15.83605Z","end":"2026-04-21T21:12:16.115445Z","steps":["trace[2061415227] 'agreement among raft nodes before linearized reading' (duration: 279.18039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:17.937869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634126820,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:12:18.438959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634126820,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:12:18.83382Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017654932s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-21T21:12:18.979633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.171571ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-21T21:12:18.979899Z","caller":"traceutil/trace.go:171","msg":"trace[1909063963] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"1.54205325s","start":"2026-04-21T21:12:17.437827Z","end":"2026-04-21T21:12:18.97988Z","steps":["trace[1909063963] 'read index received' (duration: 1.396659304s)","trace[1909063963] 'applied index is now lower than readState.Index' (duration: 145.392786ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:18.98026Z","caller":"traceutil/trace.go:171","msg":"trace[574085211] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"413.076724ms","start":"2026-04-21T21:12:18.567158Z","end":"2026-04-21T21:12:18.980235Z","steps":["trace[574085211] 'process raft request' (duration: 412.593373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:18.980389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:18.567133Z","time spent":"413.184736ms","remote":"127.0.0.1:34236","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-04-21T21:12:19.020201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.295707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T21:12:19.02027Z","caller":"traceutil/trace.go:171","msg":"trace[1550137555] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1044; }","duration":"942.406549ms","start":"2026-04-21T21:12:18.077845Z","end":"2026-04-21T21:12:19.020251Z","steps":["trace[1550137555] 'agreement among raft nodes before linearized reading' (duration: 902.362955ms)","trace[1550137555] 'count revisions from in-memory index tree' (duration: 39.947172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:19.020288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.070522ms","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-04-21T21:12:19.020321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:18.077827Z","time spent":"942.483162ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-21T21:12:19.020363Z","caller":"traceutil/trace.go:171","msg":"trace[368874412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"900.205555ms","start":"2026-04-21T21:12:18.120136Z","end":"2026-04-21T21:12:19.020341Z","steps":["trace[368874412] 'agreement among raft nodes before linearized reading' (duration: 860.056858ms)","trace[368874412] 'range keys from in-memory index tree' (duration: 39.945922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:19.020417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582585436s","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-04-21T21:12:19.020477Z","caller":"traceutil/trace.go:171","msg":"trace[1106111862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1044; }","duration":"1.582682708s","start":"2026-04-21T21:12:17.437775Z","end":"2026-04-21T21:12:19.020458Z","steps":["trace[1106111862] 'agreement among raft nodes before linearized reading' (duration: 1.542234474s)","trace[1106111862] 'range keys from in-memory index tree' (duration: 40.264779ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:19.020521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:17.437743Z","time spent":"1.58276836s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T21:12:19.020493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:18.120121Z","time spent":"900.322558ms","remote":"127.0.0.1:34236","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-04-21T21:12:19.020624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.186698ms","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-04-21T21:12:19.020668Z","caller":"traceutil/trace.go:171","msg":"trace[245673082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1044; }","duration":"901.245439ms","start":"2026-04-21T21:12:18.119409Z","end":"2026-04-21T21:12:19.020655Z","steps":["trace[245673082] 'agreement among raft nodes before linearized reading' (duration: 860.791366ms)","trace[245673082] 'range keys from in-memory index tree' (duration: 40.31437ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:19.020706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:18.119396Z","time spent":"901.303361ms","remote":"127.0.0.1:34236","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-04-21T21:12:19.020784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.174827ms","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-04-21T21:12:19.020922Z","caller":"traceutil/trace.go:171","msg":"trace[1907911951] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"971.341851ms","start":"2026-04-21T21:12:18.049563Z","end":"2026-04-21T21:12:19.020905Z","steps":["trace[1907911951] 'agreement among raft nodes before linearized reading' (duration: 930.618721ms)","trace[1907911951] 'range keys from in-memory index tree' (duration: 40.534576ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:19.020916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.707387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-21T21:12:19.020966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.983954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T21:12:19.02105Z","caller":"traceutil/trace.go:171","msg":"trace[696542748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"900.901391ms","start":"2026-04-21T21:12:18.120115Z","end":"2026-04-21T21:12:19.021016Z","steps":["trace[696542748] 'agreement among raft nodes before linearized reading' (duration: 860.079288ms)","trace[696542748] 'range keys from in-memory index tree' (duration: 39.916481ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:19.021063Z","caller":"traceutil/trace.go:171","msg":"trace[1826823972] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1044; }","duration":"245.144017ms","start":"2026-04-21T21:12:18.775895Z","end":"2026-04-21T21:12:19.021039Z","steps":["trace[1826823972] 'agreement among raft nodes before linearized reading' (duration: 204.331185ms)","trace[1826823972] 'count revisions from in-memory index tree' (duration: 40.662829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:19.020982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:18.049544Z","time spent":"971.427313ms","remote":"127.0.0.1:34236","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-04-21T21:12:19.021142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:18.120097Z","time spent":"901.017714ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T21:12:19.02082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.838953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:19.021265Z","caller":"traceutil/trace.go:171","msg":"trace[172680188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"184.327674ms","start":"2026-04-21T21:12:18.836921Z","end":"2026-04-21T21:12:19.021249Z","steps":["trace[172680188] 'agreement among raft nodes before linearized reading' (duration: 143.294397ms)","trace[172680188] 'range keys from in-memory index tree' (duration: 40.576267ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:19.23568Z","caller":"traceutil/trace.go:171","msg":"trace[1526885500] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"209.970967ms","start":"2026-04-21T21:12:19.025685Z","end":"2026-04-21T21:12:19.235656Z","steps":["trace[1526885500] 'process raft request' (duration: 209.787053ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:19.236044Z","caller":"traceutil/trace.go:171","msg":"trace[1554100751] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"209.737022ms","start":"2026-04-21T21:12:19.02626Z","end":"2026-04-21T21:12:19.235997Z","steps":["trace[1554100751] 'process raft request' (duration: 209.357462ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:19.236368Z","caller":"traceutil/trace.go:171","msg":"trace[2028552580] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"208.807691ms","start":"2026-04-21T21:12:19.027524Z","end":"2026-04-21T21:12:19.236331Z","steps":["trace[2028552580] 'process raft request' (duration: 208.298969ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:19.23643Z","caller":"traceutil/trace.go:171","msg":"trace[1180415457] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"208.788831ms","start":"2026-04-21T21:12:19.027628Z","end":"2026-04-21T21:12:19.236417Z","steps":["trace[1180415457] 'process raft request' (duration: 208.607847ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:19.236428Z","caller":"traceutil/trace.go:171","msg":"trace[1548500567] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1088; }","duration":"120.717054ms","start":"2026-04-21T21:12:19.115624Z","end":"2026-04-21T21:12:19.236341Z","steps":["trace[1548500567] 'read index received' (duration: 119.788782ms)","trace[1548500567] 'applied index is now lower than readState.Index' (duration: 926.942µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:19.236593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.95003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:19.236653Z","caller":"traceutil/trace.go:171","msg":"trace[583090273] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1049; }","duration":"121.077723ms","start":"2026-04-21T21:12:19.115561Z","end":"2026-04-21T21:12:19.236639Z","steps":["trace[583090273] 'agreement among raft nodes before linearized reading' (duration: 120.970821ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:21.296311Z","caller":"traceutil/trace.go:171","msg":"trace[716115970] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"238.06159ms","start":"2026-04-21T21:12:21.058225Z","end":"2026-04-21T21:12:21.296287Z","steps":["trace[716115970] 'read index received' (duration: 237.917596ms)","trace[716115970] 'applied index is now lower than readState.Index' (duration: 143.034µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:21.296432Z","caller":"traceutil/trace.go:171","msg":"trace[630957367] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"311.50436ms","start":"2026-04-21T21:12:20.984898Z","end":"2026-04-21T21:12:21.296402Z","steps":["trace[630957367] 'process raft request' (duration: 311.232734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:21.296588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:20.984876Z","time spent":"311.632264ms","remote":"127.0.0.1:34236","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-04-21T21:12:21.296896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.299706ms","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-04-21T21:12:21.296976Z","caller":"traceutil/trace.go:171","msg":"trace[1208293885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"238.790867ms","start":"2026-04-21T21:12:21.058172Z","end":"2026-04-21T21:12:21.296963Z","steps":["trace[1208293885] 'agreement among raft nodes before linearized reading' (duration: 238.230724ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:21.712584Z","caller":"traceutil/trace.go:171","msg":"trace[258494217] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"410.039094ms","start":"2026-04-21T21:12:21.302524Z","end":"2026-04-21T21:12:21.712563Z","steps":["trace[258494217] 'process raft request' (duration: 376.692788ms)","trace[258494217] 'compare' (duration: 32.994948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:21.712708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:21.302502Z","time spent":"410.149246ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:12:22.079077Z","caller":"traceutil/trace.go:171","msg":"trace[28092361] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"776.482259ms","start":"2026-04-21T21:12:21.302571Z","end":"2026-04-21T21:12:22.079054Z","steps":["trace[28092361] 'process raft request' (duration: 687.255517ms)","trace[28092361] 'compare' (duration: 88.859324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:22.079205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:21.302545Z","time spent":"776.593022ms","remote":"127.0.0.1:34236","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-04-21T21:12:22.079662Z","caller":"traceutil/trace.go:171","msg":"trace[167921779] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1093; }","duration":"734.406048ms","start":"2026-04-21T21:12:21.345227Z","end":"2026-04-21T21:12:22.079633Z","steps":["trace[167921779] 'read index received' (duration: 333.999841ms)","trace[167921779] 'applied index is now lower than readState.Index' (duration: 400.405067ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:22.07982Z","caller":"traceutil/trace.go:171","msg":"trace[1277078699] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"777.020863ms","start":"2026-04-21T21:12:21.302783Z","end":"2026-04-21T21:12:22.079804Z","steps":["trace[1277078699] 'process raft request' (duration: 776.728146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:22.079919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:21.302772Z","time spent":"777.094364ms","remote":"127.0.0.1:34236","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-04-21T21:12:22.080015Z","caller":"traceutil/trace.go:171","msg":"trace[473320420] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"777.258218ms","start":"2026-04-21T21:12:21.302744Z","end":"2026-04-21T21:12:22.080002Z","steps":["trace[473320420] 'process raft request' (duration: 776.277034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:22.080066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:21.302734Z","time spent":"777.307349ms","remote":"127.0.0.1:34236","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-04-21T21:12:22.080136Z","caller":"traceutil/trace.go:171","msg":"trace[3490954] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"776.676564ms","start":"2026-04-21T21:12:21.303349Z","end":"2026-04-21T21:12:22.080026Z","steps":["trace[3490954] 'process raft request' (duration: 776.237694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:22.08023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.429133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:22.080265Z","caller":"traceutil/trace.go:171","msg":"trace[569638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"242.560657ms","start":"2026-04-21T21:12:21.837695Z","end":"2026-04-21T21:12:22.080255Z","steps":["trace[569638] 'agreement among raft nodes before linearized reading' (duration: 242.518736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:22.080329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.722461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-21T21:12:22.08042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.169925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T21:12:22.080435Z","caller":"traceutil/trace.go:171","msg":"trace[724885784] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1055; }","duration":"203.870275ms","start":"2026-04-21T21:12:21.87654Z","end":"2026-04-21T21:12:22.08041Z","steps":["trace[724885784] 'agreement among raft nodes before linearized reading' (duration: 203.634639ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:22.080498Z","caller":"traceutil/trace.go:171","msg":"trace[856992970] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1055; }","duration":"735.291448ms","start":"2026-04-21T21:12:21.345188Z","end":"2026-04-21T21:12:22.08048Z","steps":["trace[856992970] 'agreement among raft nodes before linearized reading' (duration: 735.157045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:22.080557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:21.345175Z","time spent":"735.37181ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-21T21:12:22.08073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:21.303342Z","time spent":"777.089963ms","remote":"127.0.0.1:34236","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-04-21T21:12:22.080194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.887316ms","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-04-21T21:12:22.08122Z","caller":"traceutil/trace.go:171","msg":"trace[1471686892] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1055; }","duration":"577.93238ms","start":"2026-04-21T21:12:21.503267Z","end":"2026-04-21T21:12:22.0812Z","steps":["trace[1471686892] 'agreement among raft nodes before linearized reading' (duration: 576.887846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:22.081373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:21.503252Z","time spent":"578.091673ms","remote":"127.0.0.1:34394","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-04-21T21:12:27.584521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.115597ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-21T21:12:29.631302Z","caller":"traceutil/trace.go:171","msg":"trace[1955377015] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"228.795291ms","start":"2026-04-21T21:12:29.402481Z","end":"2026-04-21T21:12:29.631276Z","steps":["trace[1955377015] 'process raft request' (duration: 228.657217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:36.642018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.8097ms","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-04-21T21:12:36.6421Z","caller":"traceutil/trace.go:171","msg":"trace[979141292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1150; }","duration":"377.937464ms","start":"2026-04-21T21:12:36.264145Z","end":"2026-04-21T21:12:36.642082Z","steps":["trace[979141292] 'range keys from in-memory index tree' (duration: 377.659607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:36.642119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.325345ms","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":"warn","ts":"2026-04-21T21:12:36.642142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:36.26413Z","time spent":"378.003726ms","remote":"127.0.0.1:34236","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":"info","ts":"2026-04-21T21:12:36.64218Z","caller":"traceutil/trace.go:171","msg":"trace[1639559804] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1150; }","duration":"207.423408ms","start":"2026-04-21T21:12:36.434741Z","end":"2026-04-21T21:12:36.642165Z","steps":["trace[1639559804] 'count revisions from in-memory index tree' (duration: 207.268104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:36.642541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.188966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-21T21:12:36.642582Z","caller":"traceutil/trace.go:171","msg":"trace[1585215456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1150; }","duration":"383.278598ms","start":"2026-04-21T21:12:36.259293Z","end":"2026-04-21T21:12:36.642572Z","steps":["trace[1585215456] 'range keys from in-memory index tree' (duration: 383.058852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:36.64261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:36.259264Z","time spent":"383.339259ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T21:12:36.642785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.968654ms","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-04-21T21:12:36.642894Z","caller":"traceutil/trace.go:171","msg":"trace[1159490004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1150; }","duration":"380.091747ms","start":"2026-04-21T21:12:36.262777Z","end":"2026-04-21T21:12:36.642868Z","steps":["trace[1159490004] 'range keys from in-memory index tree' (duration: 379.81556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:36.64297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:36.262766Z","time spent":"380.193589ms","remote":"127.0.0.1:34236","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-04-21T21:12:36.642966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.688887ms","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-04-21T21:12:36.643034Z","caller":"traceutil/trace.go:171","msg":"trace[2012941365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1150; }","duration":"387.79031ms","start":"2026-04-21T21:12:36.255222Z","end":"2026-04-21T21:12:36.643012Z","steps":["trace[2012941365] 'range keys from in-memory index tree' (duration: 387.038991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:36.643099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:36.255201Z","time spent":"387.884832ms","remote":"127.0.0.1:34236","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":"info","ts":"2026-04-21T21:12:36.653395Z","caller":"traceutil/trace.go:171","msg":"trace[434318365] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"191.52521ms","start":"2026-04-21T21:12:36.461857Z","end":"2026-04-21T21:12:36.653383Z","steps":["trace[434318365] 'process raft request' (duration: 191.431037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:40.449484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61368562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:40.449567Z","caller":"traceutil/trace.go:171","msg":"trace[1153271868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1161; }","duration":"1.613801893s","start":"2026-04-21T21:12:38.835746Z","end":"2026-04-21T21:12:40.449548Z","steps":["trace[1153271868] 'range keys from in-memory index tree' (duration: 1.613630699s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:40.449606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:38.835732Z","time spent":"1.613865715s","remote":"127.0.0.1:33972","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-04-21T21:12:40.453939Z","caller":"traceutil/trace.go:171","msg":"trace[1851540515] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"495.14535ms","start":"2026-04-21T21:12:39.958772Z","end":"2026-04-21T21:12:40.453917Z","steps":["trace[1851540515] 'read index received' (duration: 494.911604ms)","trace[1851540515] 'applied index is now lower than readState.Index' (duration: 232.346µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:40.453999Z","caller":"traceutil/trace.go:171","msg":"trace[119261061] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"707.392398ms","start":"2026-04-21T21:12:39.746579Z","end":"2026-04-21T21:12:40.453971Z","steps":["trace[119261061] 'process raft request' (duration: 707.168443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:40.454139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.380756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:12:40.454181Z","caller":"traceutil/trace.go:171","msg":"trace[1662395382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1162; }","duration":"495.457938ms","start":"2026-04-21T21:12:39.958705Z","end":"2026-04-21T21:12:40.454163Z","steps":["trace[1662395382] 'agreement among raft nodes before linearized reading' (duration: 495.334425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:40.454229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:39.958683Z","time spent":"495.5398ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T21:12:40.454248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:39.746542Z","time spent":"707.508181ms","remote":"127.0.0.1:34236","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-04-21T21:12:46.809315Z","caller":"traceutil/trace.go:171","msg":"trace[1794101758] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"106.442017ms","start":"2026-04-21T21:12:46.702847Z","end":"2026-04-21T21:12:46.809289Z","steps":["trace[1794101758] 'process raft request' (duration: 106.233452ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:47.100396Z","caller":"traceutil/trace.go:171","msg":"trace[2063320168] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"156.860906ms","start":"2026-04-21T21:12:46.943507Z","end":"2026-04-21T21:12:47.100368Z","steps":["trace[2063320168] 'process raft request' (duration: 105.125695ms)","trace[2063320168] 'compare' (duration: 51.568336ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:54.947639Z","caller":"traceutil/trace.go:171","msg":"trace[743127078] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"170.615938ms","start":"2026-04-21T21:12:54.777003Z","end":"2026-04-21T21:12:54.947619Z","steps":["trace[743127078] 'read index received' (duration: 170.504195ms)","trace[743127078] 'applied index is now lower than readState.Index' (duration: 110.923µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:54.94773Z","caller":"traceutil/trace.go:171","msg":"trace[472609468] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"182.285542ms","start":"2026-04-21T21:12:54.765438Z","end":"2026-04-21T21:12:54.947724Z","steps":["trace[472609468] 'process raft request' (duration: 182.058925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:54.947942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.301339ms","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-04-21T21:12:54.947924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.889926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-b7mgd\" ","response":"range_response_count:1 size:11057"} {"level":"warn","ts":"2026-04-21T21:12:54.947987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.487069ms","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":"warn","ts":"2026-04-21T21:12:54.947978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.329829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T21:12:54.947995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.524999ms","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-04-21T21:12:54.948014Z","caller":"traceutil/trace.go:171","msg":"trace[29757154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1224; }","duration":"111.430421ms","start":"2026-04-21T21:12:54.836566Z","end":"2026-04-21T21:12:54.947996Z","steps":["trace[29757154] 'agreement among raft nodes before linearized reading' (duration: 111.195806ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:54.948037Z","caller":"traceutil/trace.go:171","msg":"trace[1801593078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"110.575101ms","start":"2026-04-21T21:12:54.837449Z","end":"2026-04-21T21:12:54.948024Z","steps":["trace[1801593078] 'agreement among raft nodes before linearized reading' (duration: 110.403837ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:54.948039Z","caller":"traceutil/trace.go:171","msg":"trace[1039134932] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-b7mgd; range_end:; response_count:1; response_revision:1224; }","duration":"171.03663ms","start":"2026-04-21T21:12:54.776978Z","end":"2026-04-21T21:12:54.948015Z","steps":["trace[1039134932] 'agreement among raft nodes before linearized reading' (duration: 170.772803ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:54.948062Z","caller":"traceutil/trace.go:171","msg":"trace[1000048082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"111.426811ms","start":"2026-04-21T21:12:54.836612Z","end":"2026-04-21T21:12:54.948039Z","steps":["trace[1000048082] 'agreement among raft nodes before linearized reading' (duration: 111.275278ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:54.948063Z","caller":"traceutil/trace.go:171","msg":"trace[1796337898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1224; }","duration":"108.610302ms","start":"2026-04-21T21:12:54.839438Z","end":"2026-04-21T21:12:54.948048Z","steps":["trace[1796337898] 'agreement among raft nodes before linearized reading' (duration: 108.392866ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:55.145476Z","caller":"traceutil/trace.go:171","msg":"trace[864104070] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"192.570787ms","start":"2026-04-21T21:12:54.952877Z","end":"2026-04-21T21:12:55.145448Z","steps":["trace[864104070] 'process raft request' (duration: 160.111908ms)","trace[864104070] 'compare' (duration: 32.364527ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:55.234129Z","caller":"traceutil/trace.go:171","msg":"trace[2017888799] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"279.016155ms","start":"2026-04-21T21:12:54.955077Z","end":"2026-04-21T21:12:55.234093Z","steps":["trace[2017888799] 'process raft request' (duration: 278.855032ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:55.234113Z","caller":"traceutil/trace.go:171","msg":"trace[890025996] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"280.524372ms","start":"2026-04-21T21:12:54.953546Z","end":"2026-04-21T21:12:55.23407Z","steps":["trace[890025996] 'process raft request' (duration: 280.163683ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:55.234258Z","caller":"traceutil/trace.go:171","msg":"trace[1837919260] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"277.454476ms","start":"2026-04-21T21:12:54.956776Z","end":"2026-04-21T21:12:55.23423Z","steps":["trace[1837919260] 'process raft request' (duration: 277.1955ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:55.234588Z","caller":"traceutil/trace.go:171","msg":"trace[1720925836] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1276; }","duration":"280.664965ms","start":"2026-04-21T21:12:54.953907Z","end":"2026-04-21T21:12:55.234572Z","steps":["trace[1720925836] 'read index received' (duration: 159.159423ms)","trace[1720925836] 'applied index is now lower than readState.Index' (duration: 121.503902ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:55.234641Z","caller":"traceutil/trace.go:171","msg":"trace[228038570] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"274.349729ms","start":"2026-04-21T21:12:54.960274Z","end":"2026-04-21T21:12:55.234624Z","steps":["trace[228038570] 'process raft request' (duration: 273.748714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:55.235089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.031975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-21T21:12:55.235199Z","caller":"traceutil/trace.go:171","msg":"trace[2138001894] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1229; }","duration":"281.315071ms","start":"2026-04-21T21:12:54.95387Z","end":"2026-04-21T21:12:55.235185Z","steps":["trace[2138001894] 'agreement among raft nodes before linearized reading' (duration: 280.789248ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:55.345732Z","caller":"traceutil/trace.go:171","msg":"trace[2026312626] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"104.13856ms","start":"2026-04-21T21:12:55.24157Z","end":"2026-04-21T21:12:55.345708Z","steps":["trace[2026312626] 'process raft request' (duration: 104.037407ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:55.345729Z","caller":"traceutil/trace.go:171","msg":"trace[1338490120] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"104.331176ms","start":"2026-04-21T21:12:55.241359Z","end":"2026-04-21T21:12:55.34569Z","steps":["trace[1338490120] 'process raft request' (duration: 104.093669ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:55.34605Z","caller":"traceutil/trace.go:171","msg":"trace[1189773357] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"103.967225ms","start":"2026-04-21T21:12:55.242065Z","end":"2026-04-21T21:12:55.346032Z","steps":["trace[1189773357] 'process raft request' (duration: 103.582165ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:56.826372Z","caller":"traceutil/trace.go:171","msg":"trace[604735172] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"438.202878ms","start":"2026-04-21T21:12:56.388142Z","end":"2026-04-21T21:12:56.826345Z","steps":["trace[604735172] 'process raft request' (duration: 438.054004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:56.826555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:56.388117Z","time spent":"438.356521ms","remote":"127.0.0.1:34146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5639,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:12:56.971077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.060887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:12:56.971166Z","caller":"traceutil/trace.go:171","msg":"trace[1631116575] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"442.766082ms","start":"2026-04-21T21:12:56.528385Z","end":"2026-04-21T21:12:56.971151Z","steps":["trace[1631116575] 'read index received' (duration: 297.887635ms)","trace[1631116575] 'applied index is now lower than readState.Index' (duration: 144.877377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:56.971378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.992578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:12:56.971424Z","caller":"traceutil/trace.go:171","msg":"trace[1062395194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1236; }","duration":"443.068829ms","start":"2026-04-21T21:12:56.528345Z","end":"2026-04-21T21:12:56.971414Z","steps":["trace[1062395194] 'agreement among raft nodes before linearized reading' (duration: 442.922456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:56.971462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:56.528326Z","time spent":"443.126371ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T21:12:56.971387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.224426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:56.971502Z","caller":"traceutil/trace.go:171","msg":"trace[323693513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"135.370699ms","start":"2026-04-21T21:12:56.836119Z","end":"2026-04-21T21:12:56.971489Z","steps":["trace[323693513] 'agreement among raft nodes before linearized reading' (duration: 135.245986ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:56.971482Z","caller":"traceutil/trace.go:171","msg":"trace[1956126730] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"450.67862ms","start":"2026-04-21T21:12:56.520772Z","end":"2026-04-21T21:12:56.97145Z","steps":["trace[1956126730] 'process raft request' (duration: 322.165582ms)","trace[1956126730] 'compare' (duration: 127.964345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:12:56.971634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:56.520744Z","time spent":"450.806533ms","remote":"127.0.0.1:34236","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-04-21T21:12:57.474093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.594889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:12:57.474348Z","caller":"traceutil/trace.go:171","msg":"trace[1370611171] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"213.689183ms","start":"2026-04-21T21:12:57.26063Z","end":"2026-04-21T21:12:57.474319Z","steps":["trace[1370611171] 'process raft request' (duration: 68.802337ms)","trace[1370611171] 'compare' (duration: 144.492327ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:57.474581Z","caller":"traceutil/trace.go:171","msg":"trace[552824613] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"213.733706ms","start":"2026-04-21T21:12:57.260812Z","end":"2026-04-21T21:12:57.474546Z","steps":["trace[552824613] 'process raft request' (duration: 213.417167ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:57.474663Z","caller":"traceutil/trace.go:171","msg":"trace[1793736834] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"131.120893ms","start":"2026-04-21T21:12:57.343522Z","end":"2026-04-21T21:12:57.474643Z","steps":["trace[1793736834] 'process raft request' (duration: 130.730263ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:57.474714Z","caller":"traceutil/trace.go:171","msg":"trace[805601327] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"213.985582ms","start":"2026-04-21T21:12:57.260718Z","end":"2026-04-21T21:12:57.474703Z","steps":["trace[805601327] 'process raft request' (duration: 213.475788ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:57.562623Z","caller":"traceutil/trace.go:171","msg":"trace[388936229] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"139.677197ms","start":"2026-04-21T21:12:57.422928Z","end":"2026-04-21T21:12:57.562605Z","steps":["trace[388936229] 'process raft request' (duration: 139.097942ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:59.148618Z","caller":"traceutil/trace.go:171","msg":"trace[1854128310] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"151.45422ms","start":"2026-04-21T21:12:58.997144Z","end":"2026-04-21T21:12:59.148598Z","steps":["trace[1854128310] 'read index received' (duration: 151.172433ms)","trace[1854128310] 'applied index is now lower than readState.Index' (duration: 280.667µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:59.148678Z","caller":"traceutil/trace.go:171","msg":"trace[502027516] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"172.870716ms","start":"2026-04-21T21:12:58.975783Z","end":"2026-04-21T21:12:59.148654Z","steps":["trace[502027516] 'process raft request' (duration: 172.618039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:59.149383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.22231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:12:59.14946Z","caller":"traceutil/trace.go:171","msg":"trace[1880571268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1244; }","duration":"152.330102ms","start":"2026-04-21T21:12:58.997114Z","end":"2026-04-21T21:12:59.149444Z","steps":["trace[1880571268] 'agreement among raft nodes before linearized reading' (duration: 151.604024ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:59.454727Z","caller":"traceutil/trace.go:171","msg":"trace[1913174479] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"129.609585ms","start":"2026-04-21T21:12:59.325093Z","end":"2026-04-21T21:12:59.454703Z","steps":["trace[1913174479] 'process raft request' (duration: 129.452371ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:59.454878Z","caller":"traceutil/trace.go:171","msg":"trace[907818967] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"124.519677ms","start":"2026-04-21T21:12:59.330336Z","end":"2026-04-21T21:12:59.454856Z","steps":["trace[907818967] 'process raft request' (duration: 124.315802ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:01.262638Z","caller":"traceutil/trace.go:171","msg":"trace[788635514] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"109.505973ms","start":"2026-04-21T21:13:01.1531Z","end":"2026-04-21T21:13:01.262606Z","steps":["trace[788635514] 'process raft request' (duration: 109.157814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:01.7736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.488513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:13:01.773744Z","caller":"traceutil/trace.go:171","msg":"trace[748868202] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"222.163175ms","start":"2026-04-21T21:13:01.551558Z","end":"2026-04-21T21:13:01.773721Z","steps":["trace[748868202] 'read index received' (duration: 40.371µs)","trace[748868202] 'applied index is now lower than readState.Index' (duration: 222.121494ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:13:01.773813Z","caller":"traceutil/trace.go:171","msg":"trace[832345295] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"275.602649ms","start":"2026-04-21T21:13:01.498154Z","end":"2026-04-21T21:13:01.773757Z","steps":["trace[832345295] 'process raft request' (duration: 30.820289ms)","trace[832345295] 'compare' (duration: 244.320639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:13:01.773961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.36316ms","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":"warn","ts":"2026-04-21T21:13:01.773995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.163216ms","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-04-21T21:13:01.774026Z","caller":"traceutil/trace.go:171","msg":"trace[2126944250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"222.216136ms","start":"2026-04-21T21:13:01.551799Z","end":"2026-04-21T21:13:01.774015Z","steps":["trace[2126944250] 'agreement among raft nodes before linearized reading' (duration: 222.053202ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:01.774051Z","caller":"traceutil/trace.go:171","msg":"trace[1822232152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"222.517264ms","start":"2026-04-21T21:13:01.551518Z","end":"2026-04-21T21:13:01.774035Z","steps":["trace[1822232152] 'agreement among raft nodes before linearized reading' (duration: 222.311759ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:08.723377Z","caller":"traceutil/trace.go:171","msg":"trace[1782828309] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"335.647459ms","start":"2026-04-21T21:13:08.387696Z","end":"2026-04-21T21:13:08.723343Z","steps":["trace[1782828309] 'process raft request' (duration: 335.478955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:08.723555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:08.387678Z","time spent":"335.791413ms","remote":"127.0.0.1:34236","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-04-21T21:13:09.851886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512978634127409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:13:10.434378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606574144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T21:13:10.435085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598430001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:13:10.43518Z","caller":"traceutil/trace.go:171","msg":"trace[274909016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"1.598536893s","start":"2026-04-21T21:13:08.836623Z","end":"2026-04-21T21:13:10.43516Z","steps":["trace[274909016] 'range keys from in-memory index tree' (duration: 1.598330468s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.435222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:08.836563Z","time spent":"1.598649307s","remote":"127.0.0.1:33972","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-04-21T21:13:10.435422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583851426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:13:10.435458Z","caller":"traceutil/trace.go:171","msg":"trace[104506836] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1318; }","duration":"1.583902637s","start":"2026-04-21T21:13:08.851541Z","end":"2026-04-21T21:13:10.435443Z","steps":["trace[104506836] 'count revisions from in-memory index tree' (duration: 1.583810315s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.435487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:08.851534Z","time spent":"1.583944708s","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-21T21:13:10.4357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.09695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:13:10.435789Z","caller":"traceutil/trace.go:171","msg":"trace[371796317] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"1.084214174s","start":"2026-04-21T21:13:09.351558Z","end":"2026-04-21T21:13:10.435772Z","steps":["trace[371796317] 'read index received' (duration: 868.861509ms)","trace[371796317] 'applied index is now lower than readState.Index' (duration: 215.351475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:13:10.435927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028055413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:13:10.435954Z","caller":"traceutil/trace.go:171","msg":"trace[1503375] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1319; }","duration":"1.028083694s","start":"2026-04-21T21:13:09.407861Z","end":"2026-04-21T21:13:10.435945Z","steps":["trace[1503375] 'agreement among raft nodes before linearized reading' (duration: 1.028001532s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.43599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.407812Z","time spent":"1.028169655s","remote":"127.0.0.1:34254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-21T21:13:10.436053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084485632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T21:13:10.436127Z","caller":"traceutil/trace.go:171","msg":"trace[923596512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"1.084604515s","start":"2026-04-21T21:13:09.351505Z","end":"2026-04-21T21:13:10.436109Z","steps":["trace[923596512] 'agreement among raft nodes before linearized reading' (duration: 1.084351668s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.436167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.351484Z","time spent":"1.084673946s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T21:13:10.43626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.695518ms","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-04-21T21:13:10.436295Z","caller":"traceutil/trace.go:171","msg":"trace[1197111302] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1319; }","duration":"510.802241ms","start":"2026-04-21T21:13:09.925481Z","end":"2026-04-21T21:13:10.436283Z","steps":["trace[1197111302] 'agreement among raft nodes before linearized reading' (duration: 510.686868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.436325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.925451Z","time spent":"510.866152ms","remote":"127.0.0.1:34236","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":"warn","ts":"2026-04-21T21:13:10.436442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.135527ms","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-04-21T21:13:10.436465Z","caller":"traceutil/trace.go:171","msg":"trace[875559075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"577.178757ms","start":"2026-04-21T21:13:09.859278Z","end":"2026-04-21T21:13:10.436457Z","steps":["trace[875559075] 'agreement among raft nodes before linearized reading' (duration: 577.110395ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:10.436469Z","caller":"traceutil/trace.go:171","msg":"trace[1456161782] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"1.109565597s","start":"2026-04-21T21:13:09.326885Z","end":"2026-04-21T21:13:10.436451Z","steps":["trace[1456161782] 'process raft request' (duration: 893.644897ms)","trace[1456161782] 'compare' (duration: 214.659829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:13:10.436493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.859267Z","time spent":"577.220079ms","remote":"127.0.0.1:34236","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-04-21T21:13:10.434503Z","caller":"traceutil/trace.go:171","msg":"trace[791686386] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1318; }","duration":"1.606750469s","start":"2026-04-21T21:13:08.827726Z","end":"2026-04-21T21:13:10.434476Z","steps":["trace[791686386] 'count revisions from in-memory index tree' (duration: 1.606479832s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.436559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.326861Z","time spent":"1.1096397s","remote":"127.0.0.1:34236","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-04-21T21:13:10.43662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:08.827704Z","time spent":"1.608891193s","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-21T21:13:10.436698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.220128ms","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-04-21T21:13:10.436729Z","caller":"traceutil/trace.go:171","msg":"trace[2102982903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"615.27084ms","start":"2026-04-21T21:13:09.82145Z","end":"2026-04-21T21:13:10.43672Z","steps":["trace[2102982903] 'agreement among raft nodes before linearized reading' (duration: 615.191738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.436754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.821439Z","time spent":"615.30994ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T21:13:10.436884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.970501ms","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-04-21T21:13:10.436906Z","caller":"traceutil/trace.go:171","msg":"trace[1438426432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"633.037642ms","start":"2026-04-21T21:13:09.803861Z","end":"2026-04-21T21:13:10.436899Z","steps":["trace[1438426432] 'agreement among raft nodes before linearized reading' (duration: 632.97097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.436931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.803829Z","time spent":"633.094824ms","remote":"127.0.0.1:34236","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-04-21T21:13:10.437058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.777689ms","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":"warn","ts":"2026-04-21T21:13:10.437057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.970244ms","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-04-21T21:13:10.437085Z","caller":"traceutil/trace.go:171","msg":"trace[1215301322] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1319; }","duration":"961.823ms","start":"2026-04-21T21:13:09.475253Z","end":"2026-04-21T21:13:10.437076Z","steps":["trace[1215301322] 'agreement among raft nodes before linearized reading' (duration: 961.754249ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:10.437097Z","caller":"traceutil/trace.go:171","msg":"trace[857525109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"962.034796ms","start":"2026-04-21T21:13:09.475048Z","end":"2026-04-21T21:13:10.437083Z","steps":["trace[857525109] 'agreement among raft nodes before linearized reading' (duration: 961.897372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:10.437129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.475034Z","time spent":"962.089337ms","remote":"127.0.0.1:34236","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-04-21T21:13:10.437136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:09.475242Z","time spent":"961.879161ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-21T21:13:22.582944Z","caller":"traceutil/trace.go:171","msg":"trace[605113611] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"103.337549ms","start":"2026-04-21T21:13:22.479577Z","end":"2026-04-21T21:13:22.582915Z","steps":["trace[605113611] 'process raft request' (duration: 102.886528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:22.851733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.34243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:13:22.852021Z","caller":"traceutil/trace.go:171","msg":"trace[38746326] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"264.484232ms","start":"2026-04-21T21:13:22.587515Z","end":"2026-04-21T21:13:22.851999Z","steps":["trace[38746326] 'process raft request' (duration: 63.786033ms)","trace[38746326] 'compare' (duration: 199.986521ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:13:22.861238Z","caller":"traceutil/trace.go:171","msg":"trace[528055854] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"273.480247ms","start":"2026-04-21T21:13:22.58774Z","end":"2026-04-21T21:13:22.86122Z","steps":["trace[528055854] 'process raft request' (duration: 273.377764ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:22.861389Z","caller":"traceutil/trace.go:171","msg":"trace[21396396] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"273.635501ms","start":"2026-04-21T21:13:22.587743Z","end":"2026-04-21T21:13:22.861378Z","steps":["trace[21396396] 'process raft request' (duration: 273.451656ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:22.861511Z","caller":"traceutil/trace.go:171","msg":"trace[709730283] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"273.075417ms","start":"2026-04-21T21:13:22.588425Z","end":"2026-04-21T21:13:22.8615Z","steps":["trace[709730283] 'process raft request' (duration: 272.918263ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:22.861543Z","caller":"traceutil/trace.go:171","msg":"trace[1472019912] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"272.983084ms","start":"2026-04-21T21:13:22.588536Z","end":"2026-04-21T21:13:22.861519Z","steps":["trace[1472019912] 'process raft request' (duration: 272.890132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:45.045029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.917862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:13:45.04511Z","caller":"traceutil/trace.go:171","msg":"trace[1476315840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1477; }","duration":"208.044485ms","start":"2026-04-21T21:13:44.837046Z","end":"2026-04-21T21:13:45.045091Z","steps":["trace[1476315840] 'range keys from in-memory index tree' (duration: 207.785519ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:14:13.211013Z","caller":"traceutil/trace.go:171","msg":"trace[581686203] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"113.986061ms","start":"2026-04-21T21:14:13.097Z","end":"2026-04-21T21:14:13.210986Z","steps":["trace[581686203] 'process raft request' (duration: 113.828077ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:14:27.470096Z","caller":"traceutil/trace.go:171","msg":"trace[682403008] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"112.811321ms","start":"2026-04-21T21:14:27.357257Z","end":"2026-04-21T21:14:27.470068Z","steps":["trace[682403008] 'process raft request' (duration: 27.974122ms)","trace[682403008] 'compare' (duration: 84.658455ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:14:27.470271Z","caller":"traceutil/trace.go:171","msg":"trace[999296765] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"109.432259ms","start":"2026-04-21T21:14:27.360827Z","end":"2026-04-21T21:14:27.470259Z","steps":["trace[999296765] 'process raft request' (duration: 109.198683ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:14:27.648141Z","caller":"traceutil/trace.go:171","msg":"trace[1571312494] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"174.069267ms","start":"2026-04-21T21:14:27.474034Z","end":"2026-04-21T21:14:27.648103Z","steps":["trace[1571312494] 'process raft request' (duration: 173.044661ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:14:27.651436Z","caller":"traceutil/trace.go:171","msg":"trace[128134607] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"176.432814ms","start":"2026-04-21T21:14:27.474981Z","end":"2026-04-21T21:14:27.651414Z","steps":["trace[128134607] 'process raft request' (duration: 176.25191ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:14:27.651649Z","caller":"traceutil/trace.go:171","msg":"trace[251298669] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"175.283506ms","start":"2026-04-21T21:14:27.476338Z","end":"2026-04-21T21:14:27.651621Z","steps":["trace[251298669] 'process raft request' (duration: 175.02319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:16:30.059174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.744311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:16:30.059391Z","caller":"traceutil/trace.go:171","msg":"trace[72146648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2263; }","duration":"223.004188ms","start":"2026-04-21T21:16:29.836361Z","end":"2026-04-21T21:16:30.059365Z","steps":["trace[72146648] 'range keys from in-memory index tree' (duration: 222.644229ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:16:30.089986Z","caller":"traceutil/trace.go:171","msg":"trace[869649325] linearizableReadLoop","detail":"{readStateIndex:2360; appliedIndex:2359; }","duration":"157.870016ms","start":"2026-04-21T21:16:29.932091Z","end":"2026-04-21T21:16:30.089961Z","steps":["trace[869649325] 'read index received' (duration: 157.389404ms)","trace[869649325] 'applied index is now lower than readState.Index' (duration: 479.272µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:16:30.090432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.196715ms","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-04-21T21:16:30.090644Z","caller":"traceutil/trace.go:171","msg":"trace[109201527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2264; }","duration":"158.601965ms","start":"2026-04-21T21:16:29.93203Z","end":"2026-04-21T21:16:30.090632Z","steps":["trace[109201527] 'agreement among raft nodes before linearized reading' (duration: 158.067171ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:16:30.090721Z","caller":"traceutil/trace.go:171","msg":"trace[1099966448] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"215.947304ms","start":"2026-04-21T21:16:29.874136Z","end":"2026-04-21T21:16:30.090084Z","steps":["trace[1099966448] 'process raft request' (duration: 215.35907ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:16:50.666526Z","caller":"traceutil/trace.go:171","msg":"trace[1343101573] transaction","detail":"{read_only:false; response_revision:2368; number_of_response:1; }","duration":"112.607296ms","start":"2026-04-21T21:16:50.553869Z","end":"2026-04-21T21:16:50.666476Z","steps":["trace[1343101573] 'process raft request' (duration: 112.310549ms)"],"step_count":1}