{"level":"warn","ts":"2026-07-25T21:28:25.134116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T21:28:25.134258Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-07-25T21:28:25.134351Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T21:28:25.134365Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-25T21:28:25.134384Z","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-07-25T21:28:25.135066Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-07-25T21:28:25.135156Z","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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-07-25T21:28:25.140236Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.814385ms"} {"level":"info","ts":"2026-07-25T21:28:25.147414Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-07-25T21:28:25.14756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T21:28:25.147599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-07-25T21:28:25.147621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T21:28:25.147645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-07-25T21:28:25.147701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-07-25T21:28:25.153701Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T21:28:25.154946Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T21:28:25.155967Z","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-07-25T21:28:25.157963Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T21:28:25.158647Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T21:28:25.158553Z","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-07-25T21:28:25.15884Z","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-07-25T21:28:25.158863Z","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-07-25T21:28:25.163853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-07-25T21:28:25.164573Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-25T21:28:25.166819Z","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-07-25T21:28:25.167033Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-25T21:28:25.167055Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-25T21:28:25.167608Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T21:28:25.167702Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T21:28:25.848856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T21:28:25.848916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T21:28:25.84896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-07-25T21:28:25.848979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-07-25T21:28:25.848991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-25T21:28:25.849007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-07-25T21:28:25.849026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-25T21:28:25.89328Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T21:28:25.899362Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T21:28:25.899502Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T21:28:25.899352Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T21:28:25.90018Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T21:28:25.90022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T21:28:25.902164Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T21:28:25.90267Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-07-25T21:28:25.904867Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T21:28:25.905015Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T21:28:25.905061Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T21:28:49.753205Z","caller":"traceutil/trace.go:171","msg":"trace[484413497] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"339.106752ms","start":"2026-07-25T21:28:49.414075Z","end":"2026-07-25T21:28:49.753182Z","steps":["trace[484413497] 'read index received' (duration: 339.01078ms)","trace[484413497] 'applied index is now lower than readState.Index' (duration: 94.802µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:28:49.753299Z","caller":"traceutil/trace.go:171","msg":"trace[806744633] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"339.99448ms","start":"2026-07-25T21:28:49.413254Z","end":"2026-07-25T21:28:49.753249Z","steps":["trace[806744633] 'process raft request' (duration: 339.780526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:28:49.753426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.333876ms","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-07-25T21:28:49.75349Z","caller":"traceutil/trace.go:171","msg":"trace[255381628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:434; }","duration":"339.458439ms","start":"2026-07-25T21:28:49.414016Z","end":"2026-07-25T21:28:49.753474Z","steps":["trace[255381628] 'agreement among raft nodes before linearized reading' (duration: 339.313166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:28:49.753544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:28:49.413997Z","time spent":"339.53744ms","remote":"127.0.0.1:40532","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-07-25T21:28:49.753711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.398717ms","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-07-25T21:28:49.753777Z","caller":"traceutil/trace.go:171","msg":"trace[1897292088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"339.49935ms","start":"2026-07-25T21:28:49.41426Z","end":"2026-07-25T21:28:49.753759Z","steps":["trace[1897292088] 'agreement among raft nodes before linearized reading' (duration: 339.341127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:28:49.753758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.493238ms","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-07-25T21:28:49.753826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:28:49.414249Z","time spent":"339.565391ms","remote":"127.0.0.1:40532","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-07-25T21:28:49.753846Z","caller":"traceutil/trace.go:171","msg":"trace[401262922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"114.643321ms","start":"2026-07-25T21:28:49.63918Z","end":"2026-07-25T21:28:49.753823Z","steps":["trace[401262922] 'agreement among raft nodes before linearized reading' (duration: 114.449607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:28:49.754085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:28:49.413218Z","time spent":"340.164983ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:29:10.575452Z","caller":"traceutil/trace.go:171","msg":"trace[1209225478] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"368.047214ms","start":"2026-07-25T21:29:10.207374Z","end":"2026-07-25T21:29:10.575421Z","steps":["trace[1209225478] 'process raft request' (duration: 364.039652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:10.575652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:10.207346Z","time spent":"368.202898ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:29:10.57797Z","caller":"traceutil/trace.go:171","msg":"trace[1260264597] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"154.52763ms","start":"2026-07-25T21:29:10.423422Z","end":"2026-07-25T21:29:10.577949Z","steps":["trace[1260264597] 'process raft request' (duration: 154.457349ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:29:10.57811Z","caller":"traceutil/trace.go:171","msg":"trace[1186287048] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"354.290507ms","start":"2026-07-25T21:29:10.223801Z","end":"2026-07-25T21:29:10.578091Z","steps":["trace[1186287048] 'process raft request' (duration: 353.90917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:10.578212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:10.223777Z","time spent":"354.385198ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T21:29:10.578325Z","caller":"traceutil/trace.go:171","msg":"trace[2045162021] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"303.452999ms","start":"2026-07-25T21:29:10.274865Z","end":"2026-07-25T21:29:10.578318Z","steps":["trace[2045162021] 'process raft request' (duration: 302.94347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:10.578359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:10.274848Z","time spent":"303.496139ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T21:29:12.934929Z","caller":"traceutil/trace.go:171","msg":"trace[1666723469] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"212.287293ms","start":"2026-07-25T21:29:12.722615Z","end":"2026-07-25T21:29:12.934902Z","steps":["trace[1666723469] 'read index received' (duration: 208.700819ms)","trace[1666723469] 'applied index is now lower than readState.Index' (duration: 3.585464ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:29:12.935101Z","caller":"traceutil/trace.go:171","msg":"trace[1935051642] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"261.533509ms","start":"2026-07-25T21:29:12.673557Z","end":"2026-07-25T21:29:12.935091Z","steps":["trace[1935051642] 'process raft request' (duration: 257.857614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:12.935253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.621829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:29:12.935289Z","caller":"traceutil/trace.go:171","msg":"trace[1398268272] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:673; }","duration":"212.7001ms","start":"2026-07-25T21:29:12.722576Z","end":"2026-07-25T21:29:12.935276Z","steps":["trace[1398268272] 'agreement among raft nodes before linearized reading' (duration: 212.632499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:12.935552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.102856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-07-25T21:29:12.935589Z","caller":"traceutil/trace.go:171","msg":"trace[2013769861] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:673; }","duration":"113.173416ms","start":"2026-07-25T21:29:12.822407Z","end":"2026-07-25T21:29:12.93558Z","steps":["trace[2013769861] 'agreement among raft nodes before linearized reading' (duration: 113.044464ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:29:16.929121Z","caller":"traceutil/trace.go:171","msg":"trace[1562181543] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"110.414578ms","start":"2026-07-25T21:29:16.818687Z","end":"2026-07-25T21:29:16.929102Z","steps":["trace[1562181543] 'process raft request' (duration: 110.258875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:18.251836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.450738ms","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-07-25T21:29:18.25198Z","caller":"traceutil/trace.go:171","msg":"trace[1141675407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"319.657491ms","start":"2026-07-25T21:29:17.932292Z","end":"2026-07-25T21:29:18.25195Z","steps":["trace[1141675407] 'range keys from in-memory index tree' (duration: 319.330706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:18.25205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:17.932281Z","time spent":"319.753793ms","remote":"127.0.0.1:40532","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-07-25T21:29:18.252042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.583377ms","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-07-25T21:29:18.252085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.577384ms","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-07-25T21:29:18.252178Z","caller":"traceutil/trace.go:171","msg":"trace[1570667622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"321.729007ms","start":"2026-07-25T21:29:17.930424Z","end":"2026-07-25T21:29:18.252153Z","steps":["trace[1570667622] 'range keys from in-memory index tree' (duration: 321.409041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:18.252228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:17.930395Z","time spent":"321.822668ms","remote":"127.0.0.1:40532","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-07-25T21:29:18.252122Z","caller":"traceutil/trace.go:171","msg":"trace[920196758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"320.725049ms","start":"2026-07-25T21:29:17.931377Z","end":"2026-07-25T21:29:18.252102Z","steps":["trace[920196758] 'range keys from in-memory index tree' (duration: 320.459745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:18.252348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:17.931355Z","time spent":"320.972314ms","remote":"127.0.0.1:40532","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-07-25T21:29:18.252374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.07369ms","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-07-25T21:29:18.252449Z","caller":"traceutil/trace.go:171","msg":"trace[2036479110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"323.799023ms","start":"2026-07-25T21:29:17.928627Z","end":"2026-07-25T21:29:18.252426Z","steps":["trace[2036479110] 'range keys from in-memory index tree' (duration: 322.936838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:18.252498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:17.928596Z","time spent":"323.887464ms","remote":"127.0.0.1:40532","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-07-25T21:29:18.254676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.584182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:29:18.25476Z","caller":"traceutil/trace.go:171","msg":"trace[1052903400] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"249.911938ms","start":"2026-07-25T21:29:18.004829Z","end":"2026-07-25T21:29:18.254741Z","steps":["trace[1052903400] 'read index received' (duration: 73.815427ms)","trace[1052903400] 'applied index is now lower than readState.Index' (duration: 176.095661ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:29:18.2548Z","caller":"traceutil/trace.go:171","msg":"trace[921882366] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"293.452428ms","start":"2026-07-25T21:29:17.961319Z","end":"2026-07-25T21:29:18.254771Z","steps":["trace[921882366] 'process raft request' (duration: 117.321117ms)","trace[921882366] 'compare' (duration: 173.267592ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:29:18.254835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.027079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:29:18.254857Z","caller":"traceutil/trace.go:171","msg":"trace[986732184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"250.098201ms","start":"2026-07-25T21:29:18.004753Z","end":"2026-07-25T21:29:18.254851Z","steps":["trace[986732184] 'agreement among raft nodes before linearized reading' (duration: 250.026429ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:29:18.588497Z","caller":"traceutil/trace.go:171","msg":"trace[1266569875] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"329.879449ms","start":"2026-07-25T21:29:18.258596Z","end":"2026-07-25T21:29:18.588475Z","steps":["trace[1266569875] 'process raft request' (duration: 313.479387ms)","trace[1266569875] 'compare' (duration: 16.28393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:29:18.588681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:18.258582Z","time spent":"329.97374ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:29:18.625998Z","caller":"traceutil/trace.go:171","msg":"trace[507908826] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"367.414883ms","start":"2026-07-25T21:29:18.258558Z","end":"2026-07-25T21:29:18.625973Z","steps":["trace[507908826] 'process raft request' (duration: 367.209689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:18.626141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:18.258521Z","time spent":"367.546895ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:29:18.626258Z","caller":"traceutil/trace.go:171","msg":"trace[12603322] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"367.454614ms","start":"2026-07-25T21:29:18.258781Z","end":"2026-07-25T21:29:18.626236Z","steps":["trace[12603322] 'process raft request' (duration: 367.117138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:18.626325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:18.258749Z","time spent":"367.543224ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:29:18.626444Z","caller":"traceutil/trace.go:171","msg":"trace[1676870234] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"366.916824ms","start":"2026-07-25T21:29:18.259517Z","end":"2026-07-25T21:29:18.626434Z","steps":["trace[1676870234] 'process raft request' (duration: 366.585418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:29:18.626487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:29:18.259508Z","time spent":"366.959394ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:29:33.834248Z","caller":"traceutil/trace.go:171","msg":"trace[1655770461] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"237.673292ms","start":"2026-07-25T21:29:33.596554Z","end":"2026-07-25T21:29:33.834228Z","steps":["trace[1655770461] 'process raft request' (duration: 237.53768ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:29:34.209851Z","caller":"traceutil/trace.go:171","msg":"trace[583672444] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"288.894152ms","start":"2026-07-25T21:29:33.920931Z","end":"2026-07-25T21:29:34.209825Z","steps":["trace[583672444] 'process raft request' (duration: 288.753279ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:30:04.12133Z","caller":"traceutil/trace.go:171","msg":"trace[1540890366] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"135.471797ms","start":"2026-07-25T21:30:03.985827Z","end":"2026-07-25T21:30:04.121298Z","steps":["trace[1540890366] 'process raft request' (duration: 135.302265ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:30:26.507522Z","caller":"traceutil/trace.go:171","msg":"trace[1064629192] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"275.770105ms","start":"2026-07-25T21:30:26.23173Z","end":"2026-07-25T21:30:26.5075Z","steps":["trace[1064629192] 'process raft request' (duration: 275.643753ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:30:44.041413Z","caller":"traceutil/trace.go:171","msg":"trace[1093405478] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"316.001084ms","start":"2026-07-25T21:30:43.725384Z","end":"2026-07-25T21:30:44.041385Z","steps":["trace[1093405478] 'process raft request' (duration: 315.72255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:30:44.041558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:30:43.725355Z","time spent":"316.122635ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:31:01.448057Z","caller":"traceutil/trace.go:171","msg":"trace[1401338053] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"233.993146ms","start":"2026-07-25T21:31:01.214036Z","end":"2026-07-25T21:31:01.448029Z","steps":["trace[1401338053] 'process raft request' (duration: 233.782633ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:31:01.448147Z","caller":"traceutil/trace.go:171","msg":"trace[353194424] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"233.241975ms","start":"2026-07-25T21:31:01.214876Z","end":"2026-07-25T21:31:01.448118Z","steps":["trace[353194424] 'process raft request' (duration: 233.084673ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:31:01.448213Z","caller":"traceutil/trace.go:171","msg":"trace[471322570] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"232.596327ms","start":"2026-07-25T21:31:01.215599Z","end":"2026-07-25T21:31:01.448195Z","steps":["trace[471322570] 'process raft request' (duration: 232.389414ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:31:01.628822Z","caller":"traceutil/trace.go:171","msg":"trace[1051235861] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"104.228856ms","start":"2026-07-25T21:31:01.52457Z","end":"2026-07-25T21:31:01.628799Z","steps":["trace[1051235861] 'process raft request' (duration: 58.943888ms)","trace[1051235861] 'compare' (duration: 45.084815ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:31:02.977035Z","caller":"traceutil/trace.go:171","msg":"trace[1268456232] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"201.904081ms","start":"2026-07-25T21:31:02.775101Z","end":"2026-07-25T21:31:02.977005Z","steps":["trace[1268456232] 'process raft request' (duration: 201.739519ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:31:03.358772Z","caller":"traceutil/trace.go:171","msg":"trace[24131562] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"128.256689ms","start":"2026-07-25T21:31:03.230487Z","end":"2026-07-25T21:31:03.358744Z","steps":["trace[24131562] 'process raft request' (duration: 128.115517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:31:03.907628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.66091ms","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-07-25T21:31:03.907776Z","caller":"traceutil/trace.go:171","msg":"trace[132378348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"455.866143ms","start":"2026-07-25T21:31:03.451891Z","end":"2026-07-25T21:31:03.907758Z","steps":["trace[132378348] 'range keys from in-memory index tree' (duration: 455.439348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:31:03.908476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:31:03.451868Z","time spent":"456.591183ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T21:31:03.907785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.490138ms","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-07-25T21:31:03.908756Z","caller":"traceutil/trace.go:171","msg":"trace[910628932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"456.491262ms","start":"2026-07-25T21:31:03.45224Z","end":"2026-07-25T21:31:03.908731Z","steps":["trace[910628932] 'range keys from in-memory index tree' (duration: 455.322536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:31:03.908835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:31:03.452233Z","time spent":"456.582733ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T21:31:03.907785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.792932ms","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-07-25T21:31:03.908924Z","caller":"traceutil/trace.go:171","msg":"trace[368441766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"456.985128ms","start":"2026-07-25T21:31:03.45192Z","end":"2026-07-25T21:31:03.908905Z","steps":["trace[368441766] 'range keys from in-memory index tree' (duration: 455.63328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:31:03.90898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:31:03.451898Z","time spent":"457.0655ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T21:31:03.914339Z","caller":"traceutil/trace.go:171","msg":"trace[1423087467] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"280.528981ms","start":"2026-07-25T21:31:03.633791Z","end":"2026-07-25T21:31:03.91432Z","steps":["trace[1423087467] 'process raft request' (duration: 280.380049ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:31:04.107227Z","caller":"traceutil/trace.go:171","msg":"trace[2077105497] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"192.839045ms","start":"2026-07-25T21:31:03.914363Z","end":"2026-07-25T21:31:04.107202Z","steps":["trace[2077105497] 'process raft request' (duration: 192.708743ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:31:04.120162Z","caller":"traceutil/trace.go:171","msg":"trace[54185552] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1270; }","duration":"115.339859ms","start":"2026-07-25T21:31:04.004804Z","end":"2026-07-25T21:31:04.120144Z","steps":["trace[54185552] 'read index received' (duration: 102.42612ms)","trace[54185552] 'applied index is now lower than readState.Index' (duration: 12.913069ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:31:04.120258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.448452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:31:04.120539Z","caller":"traceutil/trace.go:171","msg":"trace[1472687607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"115.771866ms","start":"2026-07-25T21:31:04.004756Z","end":"2026-07-25T21:31:04.120528Z","steps":["trace[1472687607] 'agreement among raft nodes before linearized reading' (duration: 115.467912ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:31:04.12037Z","caller":"traceutil/trace.go:171","msg":"trace[852213970] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"205.546051ms","start":"2026-07-25T21:31:03.914799Z","end":"2026-07-25T21:31:04.120345Z","steps":["trace[852213970] 'process raft request' (duration: 205.321808ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:31:04.120384Z","caller":"traceutil/trace.go:171","msg":"trace[1734325550] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"205.862865ms","start":"2026-07-25T21:31:03.914497Z","end":"2026-07-25T21:31:04.12036Z","steps":["trace[1734325550] 'process raft request' (duration: 205.541151ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:32:27.523282Z","caller":"traceutil/trace.go:171","msg":"trace[1821151254] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"150.730984ms","start":"2026-07-25T21:32:27.372525Z","end":"2026-07-25T21:32:27.523256Z","steps":["trace[1821151254] 'process raft request' (duration: 150.607321ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:32:28.508433Z","caller":"traceutil/trace.go:171","msg":"trace[333613471] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"268.786388ms","start":"2026-07-25T21:32:28.23961Z","end":"2026-07-25T21:32:28.508396Z","steps":["trace[333613471] 'process raft request' (duration: 268.539622ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:32:54.738202Z","caller":"traceutil/trace.go:171","msg":"trace[1779349464] linearizableReadLoop","detail":"{readStateIndex:1656; appliedIndex:1655; }","duration":"102.313221ms","start":"2026-07-25T21:32:54.635863Z","end":"2026-07-25T21:32:54.738176Z","steps":["trace[1779349464] 'read index received' (duration: 72.16718ms)","trace[1779349464] 'applied index is now lower than readState.Index' (duration: 30.144841ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:32:54.738284Z","caller":"traceutil/trace.go:171","msg":"trace[1462150489] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"103.526531ms","start":"2026-07-25T21:32:54.634719Z","end":"2026-07-25T21:32:54.738246Z","steps":["trace[1462150489] 'process raft request' (duration: 73.36719ms)","trace[1462150489] 'compare' (duration: 29.915185ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:32:54.738424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.535657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T21:32:54.738514Z","caller":"traceutil/trace.go:171","msg":"trace[1708701849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1582; }","duration":"102.66428ms","start":"2026-07-25T21:32:54.63583Z","end":"2026-07-25T21:32:54.738495Z","steps":["trace[1708701849] 'agreement among raft nodes before linearized reading' (duration: 102.439915ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:32:56.836686Z","caller":"traceutil/trace.go:171","msg":"trace[777274255] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"207.831701ms","start":"2026-07-25T21:32:56.628829Z","end":"2026-07-25T21:32:56.83666Z","steps":["trace[777274255] 'process raft request' (duration: 207.272047ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:33:00.002257Z","caller":"traceutil/trace.go:171","msg":"trace[1065469753] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"285.821153ms","start":"2026-07-25T21:32:59.716413Z","end":"2026-07-25T21:33:00.002234Z","steps":["trace[1065469753] 'process raft request' (duration: 285.68807ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:33:03.097958Z","caller":"traceutil/trace.go:171","msg":"trace[556624873] transaction","detail":"{read_only:false; response_revision:1611; number_of_response:1; }","duration":"154.396625ms","start":"2026-07-25T21:33:02.943509Z","end":"2026-07-25T21:33:03.097906Z","steps":["trace[556624873] 'process raft request' (duration: 154.21853ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:33:03.098008Z","caller":"traceutil/trace.go:171","msg":"trace[1561389226] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"154.486187ms","start":"2026-07-25T21:33:02.943454Z","end":"2026-07-25T21:33:03.09794Z","steps":["trace[1561389226] 'process raft request' (duration: 116.764432ms)","trace[1561389226] 'compare' (duration: 37.360506ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:33:28.297227Z","caller":"traceutil/trace.go:171","msg":"trace[1443092817] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"148.133533ms","start":"2026-07-25T21:33:28.149041Z","end":"2026-07-25T21:33:28.297174Z","steps":["trace[1443092817] 'process raft request' (duration: 147.873586ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:33:28.874908Z","caller":"traceutil/trace.go:171","msg":"trace[1302483813] linearizableReadLoop","detail":"{readStateIndex:1773; appliedIndex:1772; }","duration":"149.516677ms","start":"2026-07-25T21:33:28.725348Z","end":"2026-07-25T21:33:28.874865Z","steps":["trace[1302483813] 'read index received' (duration: 149.167008ms)","trace[1302483813] 'applied index is now lower than readState.Index' (duration: 347.599µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:33:28.875001Z","caller":"traceutil/trace.go:171","msg":"trace[2102968435] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"572.27251ms","start":"2026-07-25T21:33:28.30265Z","end":"2026-07-25T21:33:28.874922Z","steps":["trace[2102968435] 'process raft request' (duration: 571.908801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:33:28.87521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.822804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T21:33:28.87525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:33:28.30262Z","time spent":"572.496746ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:33:28.875396Z","caller":"traceutil/trace.go:171","msg":"trace[417072132] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1693; }","duration":"149.991268ms","start":"2026-07-25T21:33:28.725339Z","end":"2026-07-25T21:33:28.875331Z","steps":["trace[417072132] 'agreement among raft nodes before linearized reading' (duration: 149.769693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:33:30.434036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.096474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T21:33:30.434057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.816905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T21:33:30.434124Z","caller":"traceutil/trace.go:171","msg":"trace[2075811282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1698; }","duration":"428.268388ms","start":"2026-07-25T21:33:30.005836Z","end":"2026-07-25T21:33:30.434105Z","steps":["trace[2075811282] 'range keys from in-memory index tree' (duration: 427.9106ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:33:30.434148Z","caller":"traceutil/trace.go:171","msg":"trace[496921883] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1698; }","duration":"678.972379ms","start":"2026-07-25T21:33:29.755154Z","end":"2026-07-25T21:33:30.434127Z","steps":["trace[496921883] 'count revisions from in-memory index tree' (duration: 678.733793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:33:30.434168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:33:30.005801Z","time spent":"428.35896ms","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T21:33:30.434191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:33:29.75513Z","time spent":"679.049921ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-25T21:33:30.434361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022560552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:33:30.434412Z","caller":"traceutil/trace.go:171","msg":"trace[323206323] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1698; }","duration":"1.022660475s","start":"2026-07-25T21:33:29.411738Z","end":"2026-07-25T21:33:30.434399Z","steps":["trace[323206323] 'count revisions from in-memory index tree' (duration: 1.02247072s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:33:30.434449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:33:29.411715Z","time spent":"1.022723757s","remote":"127.0.0.1:44346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-25T21:33:30.433991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.24612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:33:30.4346Z","caller":"traceutil/trace.go:171","msg":"trace[2047331788] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1698; }","duration":"492.919826ms","start":"2026-07-25T21:33:29.941653Z","end":"2026-07-25T21:33:30.434573Z","steps":["trace[2047331788] 'count revisions from in-memory index tree' (duration: 492.071575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:33:30.434659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:33:29.94162Z","time spent":"493.021519ms","remote":"127.0.0.1:44442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-25T21:33:30.434655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.807841ms","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-07-25T21:33:30.434849Z","caller":"traceutil/trace.go:171","msg":"trace[2114706262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1698; }","duration":"133.064417ms","start":"2026-07-25T21:33:30.301767Z","end":"2026-07-25T21:33:30.434832Z","steps":["trace[2114706262] 'range keys from in-memory index tree' (duration: 132.573365ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:33:30.629844Z","caller":"traceutil/trace.go:171","msg":"trace[1742952425] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"189.928507ms","start":"2026-07-25T21:33:30.439878Z","end":"2026-07-25T21:33:30.629807Z","steps":["trace[1742952425] 'process raft request' (duration: 189.68027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:33:32.016524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.89079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:33:32.016661Z","caller":"traceutil/trace.go:171","msg":"trace[1520303604] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"744.578159ms","start":"2026-07-25T21:33:31.272057Z","end":"2026-07-25T21:33:32.016635Z","steps":["trace[1520303604] 'process raft request' (duration: 379.440793ms)","trace[1520303604] 'compare' (duration: 364.564022ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:33:32.017005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:33:31.272036Z","time spent":"744.688072ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:33:32.077843Z","caller":"traceutil/trace.go:171","msg":"trace[1610219178] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"802.886983ms","start":"2026-07-25T21:33:31.274908Z","end":"2026-07-25T21:33:32.077794Z","steps":["trace[1610219178] 'process raft request' (duration: 800.877414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:33:32.078177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:33:31.274876Z","time spent":"803.146819ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:36:54.271425Z","caller":"traceutil/trace.go:171","msg":"trace[1849426814] linearizableReadLoop","detail":"{readStateIndex:2488; appliedIndex:2487; }","duration":"113.168374ms","start":"2026-07-25T21:36:54.158227Z","end":"2026-07-25T21:36:54.271395Z","steps":["trace[1849426814] 'read index received' (duration: 112.914668ms)","trace[1849426814] 'applied index is now lower than readState.Index' (duration: 252.386µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:36:54.271668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.42156ms","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-07-25T21:36:54.271657Z","caller":"traceutil/trace.go:171","msg":"trace[314373119] transaction","detail":"{read_only:false; response_revision:2367; number_of_response:1; }","duration":"234.37471ms","start":"2026-07-25T21:36:54.03725Z","end":"2026-07-25T21:36:54.271625Z","steps":["trace[314373119] 'process raft request' (duration: 233.95209ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:36:54.27173Z","caller":"traceutil/trace.go:171","msg":"trace[383745970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2367; }","duration":"113.529183ms","start":"2026-07-25T21:36:54.158182Z","end":"2026-07-25T21:36:54.271711Z","steps":["trace[383745970] 'agreement among raft nodes before linearized reading' (duration: 113.332038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:36:54.581478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.641875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:36:54.581601Z","caller":"traceutil/trace.go:171","msg":"trace[175838934] transaction","detail":"{read_only:false; response_revision:2368; number_of_response:1; }","duration":"306.797871ms","start":"2026-07-25T21:36:54.274788Z","end":"2026-07-25T21:36:54.581585Z","steps":["trace[175838934] 'process raft request' (duration: 128.991842ms)","trace[175838934] 'compare' (duration: 177.485941ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:36:54.581654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:36:54.274768Z","time spent":"306.865102ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:36:54.695976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.416464ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T21:36:54.696061Z","caller":"traceutil/trace.go:171","msg":"trace[262930374] linearizableReadLoop","detail":"{readStateIndex:2490; appliedIndex:2488; }","duration":"398.708017ms","start":"2026-07-25T21:36:54.29734Z","end":"2026-07-25T21:36:54.696048Z","steps":["trace[262930374] 'read index received' (duration: 106.450684ms)","trace[262930374] 'applied index is now lower than readState.Index' (duration: 292.256213ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:36:54.69619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.85113ms","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-07-25T21:36:54.696221Z","caller":"traceutil/trace.go:171","msg":"trace[1002695003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2368; }","duration":"398.918472ms","start":"2026-07-25T21:36:54.297296Z","end":"2026-07-25T21:36:54.696215Z","steps":["trace[1002695003] 'agreement among raft nodes before linearized reading' (duration: 398.784629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:36:54.696246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:36:54.297282Z","time spent":"398.958602ms","remote":"127.0.0.1:40532","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-07-25T21:36:55.910428Z","caller":"traceutil/trace.go:171","msg":"trace[756894795] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"139.789172ms","start":"2026-07-25T21:36:55.770598Z","end":"2026-07-25T21:36:55.910387Z","steps":["trace[756894795] 'process raft request' (duration: 139.524615ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:37:11.40504Z","caller":"traceutil/trace.go:171","msg":"trace[1033250737] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"116.213429ms","start":"2026-07-25T21:37:11.288805Z","end":"2026-07-25T21:37:11.405019Z","steps":["trace[1033250737] 'process raft request' (duration: 116.085696ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:38:25.964353Z","caller":"traceutil/trace.go:171","msg":"trace[411472414] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"148.153504ms","start":"2026-07-25T21:38:25.816179Z","end":"2026-07-25T21:38:25.964333Z","steps":["trace[411472414] 'process raft request' (duration: 148.013941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:38:26.970023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.843056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:38:26.970117Z","caller":"traceutil/trace.go:171","msg":"trace[803378222] linearizableReadLoop","detail":"{readStateIndex:2808; appliedIndex:2807; }","duration":"965.306713ms","start":"2026-07-25T21:38:26.004795Z","end":"2026-07-25T21:38:26.970102Z","steps":["trace[803378222] 'read index received' (duration: 404.237031ms)","trace[803378222] 'applied index is now lower than readState.Index' (duration: 561.068512ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:38:26.970181Z","caller":"traceutil/trace.go:171","msg":"trace[665911107] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"1.041172943s","start":"2026-07-25T21:38:25.928997Z","end":"2026-07-25T21:38:26.97017Z","steps":["trace[665911107] 'process raft request' (duration: 480.115121ms)","trace[665911107] 'compare' (duration: 560.732184ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:38:26.970235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:38:25.928979Z","time spent":"1.041233544s","remote":"127.0.0.1:35986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:38:26.970509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.701683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:38:26.970602Z","caller":"traceutil/trace.go:171","msg":"trace[1004911752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2668; }","duration":"965.802115ms","start":"2026-07-25T21:38:26.00478Z","end":"2026-07-25T21:38:26.970582Z","steps":["trace[1004911752] 'agreement among raft nodes before linearized reading' (duration: 965.58619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:38:26.970754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:38:26.004721Z","time spent":"966.02074ms","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T21:38:26.993872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.949613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:38:26.993947Z","caller":"traceutil/trace.go:171","msg":"trace[1337638385] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2669; }","duration":"557.066236ms","start":"2026-07-25T21:38:26.436866Z","end":"2026-07-25T21:38:26.993932Z","steps":["trace[1337638385] 'agreement among raft nodes before linearized reading' (duration: 556.943713ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:38:26.993896Z","caller":"traceutil/trace.go:171","msg":"trace[31553586] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"253.555837ms","start":"2026-07-25T21:38:26.740307Z","end":"2026-07-25T21:38:26.993862Z","steps":["trace[31553586] 'process raft request' (duration: 253.295531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:38:26.993978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:38:26.436845Z","time spent":"557.124827ms","remote":"127.0.0.1:44330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-25T21:38:26.993975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.63535ms","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":"warn","ts":"2026-07-25T21:38:26.994134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.222485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-25T21:38:26.994222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.770676ms","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-07-25T21:38:26.994234Z","caller":"traceutil/trace.go:171","msg":"trace[1963572489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2669; }","duration":"548.351798ms","start":"2026-07-25T21:38:26.44586Z","end":"2026-07-25T21:38:26.994211Z","steps":["trace[1963572489] 'agreement among raft nodes before linearized reading' (duration: 547.920287ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:38:26.994268Z","caller":"traceutil/trace.go:171","msg":"trace[143430530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2669; }","duration":"971.859379ms","start":"2026-07-25T21:38:26.022397Z","end":"2026-07-25T21:38:26.994257Z","steps":["trace[143430530] 'agreement among raft nodes before linearized reading' (duration: 971.692275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:38:26.994299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:38:26.445848Z","time spent":"548.435761ms","remote":"127.0.0.1:40532","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-07-25T21:38:26.994319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:38:26.022383Z","time spent":"971.9269ms","remote":"127.0.0.1:40532","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-07-25T21:38:26.994185Z","caller":"traceutil/trace.go:171","msg":"trace[292906659] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2669; }","duration":"422.900837ms","start":"2026-07-25T21:38:26.571262Z","end":"2026-07-25T21:38:26.994163Z","steps":["trace[292906659] 'agreement among raft nodes before linearized reading' (duration: 422.509337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:38:26.994724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:38:26.571243Z","time spent":"423.46146ms","remote":"127.0.0.1:41204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-07-25T21:38:26.99773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1684} {"level":"info","ts":"2026-07-25T21:38:27.053276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1684,"took":"54.748556ms","hash":1142556901,"current-db-size-bytes":23433216,"current-db-size":"23 MB","current-db-size-in-use-bytes":5500928,"current-db-size-in-use":"5.5 MB"} {"level":"info","ts":"2026-07-25T21:38:27.053338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1142556901,"revision":1684,"compact-revision":-1} {"level":"info","ts":"2026-07-25T21:39:08.555032Z","caller":"traceutil/trace.go:171","msg":"trace[416439583] linearizableReadLoop","detail":"{readStateIndex:2955; appliedIndex:2954; }","duration":"208.958368ms","start":"2026-07-25T21:39:08.346047Z","end":"2026-07-25T21:39:08.555006Z","steps":["trace[416439583] 'read index received' (duration: 208.821864ms)","trace[416439583] 'applied index is now lower than readState.Index' (duration: 135.684µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:39:08.555189Z","caller":"traceutil/trace.go:171","msg":"trace[543477620] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"215.199296ms","start":"2026-07-25T21:39:08.339954Z","end":"2026-07-25T21:39:08.555154Z","steps":["trace[543477620] 'process raft request' (duration: 214.893289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:08.556016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.936701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:08.556074Z","caller":"traceutil/trace.go:171","msg":"trace[126795910] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2806; }","duration":"210.037934ms","start":"2026-07-25T21:39:08.34602Z","end":"2026-07-25T21:39:08.556058Z","steps":["trace[126795910] 'agreement among raft nodes before linearized reading' (duration: 209.231504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:13.786931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.163565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:39:13.787029Z","caller":"traceutil/trace.go:171","msg":"trace[1227984016] linearizableReadLoop","detail":"{readStateIndex:2973; appliedIndex:2972; }","duration":"278.441533ms","start":"2026-07-25T21:39:13.508573Z","end":"2026-07-25T21:39:13.787014Z","steps":["trace[1227984016] 'read index received' (duration: 152.097414ms)","trace[1227984016] 'applied index is now lower than readState.Index' (duration: 126.342959ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:13.78715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.582336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T21:39:13.787239Z","caller":"traceutil/trace.go:171","msg":"trace[1766950869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2823; }","duration":"278.694138ms","start":"2026-07-25T21:39:13.508533Z","end":"2026-07-25T21:39:13.787227Z","steps":["trace[1766950869] 'agreement among raft nodes before linearized reading' (duration: 278.522294ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:13.787243Z","caller":"traceutil/trace.go:171","msg":"trace[503400327] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"378.132178ms","start":"2026-07-25T21:39:13.409075Z","end":"2026-07-25T21:39:13.787207Z","steps":["trace[503400327] 'process raft request' (duration: 251.634495ms)","trace[503400327] 'compare' (duration: 126.047022ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:13.787404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:13.409047Z","time spent":"378.280291ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:14.864854Z","caller":"traceutil/trace.go:171","msg":"trace[1103304358] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"257.799061ms","start":"2026-07-25T21:39:14.607034Z","end":"2026-07-25T21:39:14.864833Z","steps":["trace[1103304358] 'process raft request' (duration: 257.625497ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:14.877303Z","caller":"traceutil/trace.go:171","msg":"trace[1737815403] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"203.171119ms","start":"2026-07-25T21:39:14.674118Z","end":"2026-07-25T21:39:14.877289Z","steps":["trace[1737815403] 'process raft request' (duration: 203.070727ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:15.873082Z","caller":"traceutil/trace.go:171","msg":"trace[421399408] linearizableReadLoop","detail":"{readStateIndex:2979; appliedIndex:2978; }","duration":"393.34524ms","start":"2026-07-25T21:39:15.479705Z","end":"2026-07-25T21:39:15.87305Z","steps":["trace[421399408] 'read index received' (duration: 393.063533ms)","trace[421399408] 'applied index is now lower than readState.Index' (duration: 280.507µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:39:15.873319Z","caller":"traceutil/trace.go:171","msg":"trace[2087245757] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"595.957886ms","start":"2026-07-25T21:39:15.277341Z","end":"2026-07-25T21:39:15.873299Z","steps":["trace[2087245757] 'process raft request' (duration: 595.526236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:15.873447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.727659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-25T21:39:15.873701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:15.277321Z","time spent":"596.249893ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:15.873741Z","caller":"traceutil/trace.go:171","msg":"trace[317948087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2828; }","duration":"394.040806ms","start":"2026-07-25T21:39:15.479666Z","end":"2026-07-25T21:39:15.873707Z","steps":["trace[317948087] 'agreement among raft nodes before linearized reading' (duration: 393.650027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:15.873911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:15.479642Z","time spent":"394.230461ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T21:39:16.235314Z","caller":"traceutil/trace.go:171","msg":"trace[511514870] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"356.756568ms","start":"2026-07-25T21:39:15.878535Z","end":"2026-07-25T21:39:16.235291Z","steps":["trace[511514870] 'process raft request' (duration: 353.166773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:16.23578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:15.878515Z","time spent":"356.863981ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:16.249426Z","caller":"traceutil/trace.go:171","msg":"trace[506260601] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"370.792502ms","start":"2026-07-25T21:39:15.878614Z","end":"2026-07-25T21:39:16.249407Z","steps":["trace[506260601] 'process raft request' (duration: 370.651259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:16.249548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:15.878592Z","time spent":"370.887365ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:16.24972Z","caller":"traceutil/trace.go:171","msg":"trace[684169049] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"370.935096ms","start":"2026-07-25T21:39:15.878758Z","end":"2026-07-25T21:39:16.249693Z","steps":["trace[684169049] 'process raft request' (duration: 370.615349ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:16.249745Z","caller":"traceutil/trace.go:171","msg":"trace[843000289] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"369.683186ms","start":"2026-07-25T21:39:15.880042Z","end":"2026-07-25T21:39:16.249725Z","steps":["trace[843000289] 'process raft request' (duration: 369.570603ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:16.24974Z","caller":"traceutil/trace.go:171","msg":"trace[1722637282] linearizableReadLoop","detail":"{readStateIndex:2983; appliedIndex:2979; }","duration":"244.561046ms","start":"2026-07-25T21:39:16.005151Z","end":"2026-07-25T21:39:16.249712Z","steps":["trace[1722637282] 'read index received' (duration: 226.588508ms)","trace[1722637282] 'applied index is now lower than readState.Index' (duration: 17.971498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:16.249874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:15.878671Z","time spent":"371.122661ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:39:16.249908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.726219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:16.249974Z","caller":"traceutil/trace.go:171","msg":"trace[1026061712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2832; }","duration":"244.865342ms","start":"2026-07-25T21:39:16.005093Z","end":"2026-07-25T21:39:16.249958Z","steps":["trace[1026061712] 'agreement among raft nodes before linearized reading' (duration: 244.726329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:16.249958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:15.879932Z","time spent":"369.864491ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:39:18.504536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103949903025732,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T21:39:18.553837Z","caller":"traceutil/trace.go:171","msg":"trace[1556356409] linearizableReadLoop","detail":"{readStateIndex:2985; appliedIndex:2984; }","duration":"549.489059ms","start":"2026-07-25T21:39:18.004325Z","end":"2026-07-25T21:39:18.553814Z","steps":["trace[1556356409] 'read index received' (duration: 549.410827ms)","trace[1556356409] 'applied index is now lower than readState.Index' (duration: 77.362µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:39:18.553964Z","caller":"traceutil/trace.go:171","msg":"trace[702802400] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"673.880001ms","start":"2026-07-25T21:39:17.880051Z","end":"2026-07-25T21:39:18.553931Z","steps":["trace[702802400] 'process raft request' (duration: 673.599775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:18.554072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.728234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T21:39:18.554142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:17.880027Z","time spent":"674.033065ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:18.554147Z","caller":"traceutil/trace.go:171","msg":"trace[198948644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2834; }","duration":"549.850297ms","start":"2026-07-25T21:39:18.004278Z","end":"2026-07-25T21:39:18.554128Z","steps":["trace[198948644] 'agreement among raft nodes before linearized reading' (duration: 549.688993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:18.55421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:18.004258Z","time spent":"549.93835ms","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T21:39:18.554341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.523629ms","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-07-25T21:39:18.554534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.082025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T21:39:18.554573Z","caller":"traceutil/trace.go:171","msg":"trace[1729289620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2834; }","duration":"315.152576ms","start":"2026-07-25T21:39:18.239411Z","end":"2026-07-25T21:39:18.554563Z","steps":["trace[1729289620] 'agreement among raft nodes before linearized reading' (duration: 314.86938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:18.554602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:18.23939Z","time spent":"315.205957ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T21:39:18.554624Z","caller":"traceutil/trace.go:171","msg":"trace[856677987] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2834; }","duration":"507.898377ms","start":"2026-07-25T21:39:18.046706Z","end":"2026-07-25T21:39:18.554605Z","steps":["trace[856677987] 'agreement among raft nodes before linearized reading' (duration: 507.303333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:18.554357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.200251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T21:39:18.554772Z","caller":"traceutil/trace.go:171","msg":"trace[1417963745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2834; }","duration":"300.685882ms","start":"2026-07-25T21:39:18.254077Z","end":"2026-07-25T21:39:18.554763Z","steps":["trace[1417963745] 'agreement among raft nodes before linearized reading' (duration: 300.054917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:18.554807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:18.254049Z","time spent":"300.749313ms","remote":"127.0.0.1:40532","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-07-25T21:39:18.554435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.149989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T21:39:18.554876Z","caller":"traceutil/trace.go:171","msg":"trace[250217881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2834; }","duration":"300.59858ms","start":"2026-07-25T21:39:18.254251Z","end":"2026-07-25T21:39:18.554849Z","steps":["trace[250217881] 'agreement among raft nodes before linearized reading' (duration: 300.091678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:18.554357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.335474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T21:39:18.554948Z","caller":"traceutil/trace.go:171","msg":"trace[1018397826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2834; }","duration":"300.973138ms","start":"2026-07-25T21:39:18.253966Z","end":"2026-07-25T21:39:18.55494Z","steps":["trace[1018397826] 'agreement among raft nodes before linearized reading' (duration: 300.291422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:18.554959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:18.254239Z","time spent":"300.692692ms","remote":"127.0.0.1:40532","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-07-25T21:39:18.554976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:18.253944Z","time spent":"301.02478ms","remote":"127.0.0.1:40532","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-07-25T21:39:18.554947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:18.046684Z","time spent":"508.076342ms","remote":"127.0.0.1:40602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-07-25T21:39:18.749048Z","caller":"traceutil/trace.go:171","msg":"trace[1113436469] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"189.623747ms","start":"2026-07-25T21:39:18.559394Z","end":"2026-07-25T21:39:18.749018Z","steps":["trace[1113436469] 'process raft request' (duration: 91.535301ms)","trace[1113436469] 'compare' (duration: 97.883701ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:39:18.749318Z","caller":"traceutil/trace.go:171","msg":"trace[1353121760] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"189.040233ms","start":"2026-07-25T21:39:18.560252Z","end":"2026-07-25T21:39:18.749292Z","steps":["trace[1353121760] 'process raft request' (duration: 188.707915ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:18.74937Z","caller":"traceutil/trace.go:171","msg":"trace[1953479435] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"188.822888ms","start":"2026-07-25T21:39:18.560525Z","end":"2026-07-25T21:39:18.749348Z","steps":["trace[1953479435] 'process raft request' (duration: 188.708145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:21.090706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103949903025753,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T21:39:21.244379Z","caller":"traceutil/trace.go:171","msg":"trace[296700660] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"685.209862ms","start":"2026-07-25T21:39:20.55914Z","end":"2026-07-25T21:39:21.24435Z","steps":["trace[296700660] 'process raft request' (duration: 685.067239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:21.244529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:20.559117Z","time spent":"685.343936ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:21.256927Z","caller":"traceutil/trace.go:171","msg":"trace[2079027502] linearizableReadLoop","detail":"{readStateIndex:2994; appliedIndex:2992; }","duration":"666.693881ms","start":"2026-07-25T21:39:20.590208Z","end":"2026-07-25T21:39:21.256902Z","steps":["trace[2079027502] 'read index received' (duration: 654.004739ms)","trace[2079027502] 'applied index is now lower than readState.Index' (duration: 12.688092ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:21.25719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.401895ms","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-07-25T21:39:21.257225Z","caller":"traceutil/trace.go:171","msg":"trace[1716925884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2842; }","duration":"504.490327ms","start":"2026-07-25T21:39:20.752724Z","end":"2026-07-25T21:39:21.257215Z","steps":["trace[1716925884] 'agreement among raft nodes before linearized reading' (duration: 504.309413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:21.25726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:20.752708Z","time spent":"504.544948ms","remote":"127.0.0.1:40532","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-07-25T21:39:21.257284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.054379ms","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-07-25T21:39:21.257315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.028296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T21:39:21.257867Z","caller":"traceutil/trace.go:171","msg":"trace[496304259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2842; }","duration":"224.61838ms","start":"2026-07-25T21:39:21.033229Z","end":"2026-07-25T21:39:21.257848Z","steps":["trace[496304259] 'agreement among raft nodes before linearized reading' (duration: 223.952035ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:21.257355Z","caller":"traceutil/trace.go:171","msg":"trace[1728805780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2842; }","duration":"667.176292ms","start":"2026-07-25T21:39:20.59016Z","end":"2026-07-25T21:39:21.257336Z","steps":["trace[1728805780] 'agreement among raft nodes before linearized reading' (duration: 666.871545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:21.258085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:20.590148Z","time spent":"667.915301ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T21:39:21.257471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.583489ms","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-07-25T21:39:21.258296Z","caller":"traceutil/trace.go:171","msg":"trace[282404310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2842; }","duration":"505.416399ms","start":"2026-07-25T21:39:20.752864Z","end":"2026-07-25T21:39:21.25828Z","steps":["trace[282404310] 'agreement among raft nodes before linearized reading' (duration: 504.534728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:21.258351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:20.752857Z","time spent":"505.478101ms","remote":"127.0.0.1:40532","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-07-25T21:39:21.257607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.18395ms","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-07-25T21:39:21.258462Z","caller":"traceutil/trace.go:171","msg":"trace[1383484761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2842; }","duration":"505.03782ms","start":"2026-07-25T21:39:20.753397Z","end":"2026-07-25T21:39:21.258435Z","steps":["trace[1383484761] 'agreement among raft nodes before linearized reading' (duration: 504.132799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:21.25852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:20.753374Z","time spent":"505.130292ms","remote":"127.0.0.1:40532","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-07-25T21:39:21.257732Z","caller":"traceutil/trace.go:171","msg":"trace[985019227] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"393.944554ms","start":"2026-07-25T21:39:20.86376Z","end":"2026-07-25T21:39:21.257704Z","steps":["trace[985019227] 'process raft request' (duration: 393.007442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:21.258639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:20.863733Z","time spent":"394.850086ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:39:37.814096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103949903025984,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T21:39:37.906975Z","caller":"traceutil/trace.go:171","msg":"trace[1325788] linearizableReadLoop","detail":"{readStateIndex:3102; appliedIndex:3101; }","duration":"593.59495ms","start":"2026-07-25T21:39:37.313357Z","end":"2026-07-25T21:39:37.906952Z","steps":["trace[1325788] 'read index received' (duration: 593.378895ms)","trace[1325788] 'applied index is now lower than readState.Index' (duration: 215.105µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:39:37.907076Z","caller":"traceutil/trace.go:171","msg":"trace[349607446] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"610.494512ms","start":"2026-07-25T21:39:37.296549Z","end":"2026-07-25T21:39:37.907043Z","steps":["trace[349607446] 'process raft request' (duration: 610.204595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:37.90715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.774964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:37.907185Z","caller":"traceutil/trace.go:171","msg":"trace[828575473] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2945; }","duration":"593.873936ms","start":"2026-07-25T21:39:37.313301Z","end":"2026-07-25T21:39:37.907175Z","steps":["trace[828575473] 'agreement among raft nodes before linearized reading' (duration: 593.773644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:37.907203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.778914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-25T21:39:37.907223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:37.313275Z","time spent":"593.937958ms","remote":"127.0.0.1:44426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-25T21:39:37.907248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:37.296525Z","time spent":"610.643166ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:37.907264Z","caller":"traceutil/trace.go:171","msg":"trace[255981392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2945; }","duration":"551.882046ms","start":"2026-07-25T21:39:37.355365Z","end":"2026-07-25T21:39:37.907247Z","steps":["trace[255981392] 'agreement among raft nodes before linearized reading' (duration: 551.748073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:37.907298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:37.355348Z","time spent":"551.944268ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T21:39:37.907459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.505388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T21:39:37.907493Z","caller":"traceutil/trace.go:171","msg":"trace[1808935653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2945; }","duration":"428.557588ms","start":"2026-07-25T21:39:37.478925Z","end":"2026-07-25T21:39:37.907482Z","steps":["trace[1808935653] 'agreement among raft nodes before linearized reading' (duration: 428.480856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:37.907523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:37.478912Z","time spent":"428.603999ms","remote":"127.0.0.1:40532","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-07-25T21:39:37.907571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.732878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T21:39:37.907601Z","caller":"traceutil/trace.go:171","msg":"trace[2103584024] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2945; }","duration":"474.792ms","start":"2026-07-25T21:39:37.4328Z","end":"2026-07-25T21:39:37.907592Z","steps":["trace[2103584024] 'agreement among raft nodes before linearized reading' (duration: 474.736659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:37.90762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:37.432782Z","time spent":"474.83252ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-25T21:39:37.907626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.845053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T21:39:37.907603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.398596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T21:39:37.907654Z","caller":"traceutil/trace.go:171","msg":"trace[1571222049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2945; }","duration":"430.917204ms","start":"2026-07-25T21:39:37.476729Z","end":"2026-07-25T21:39:37.907646Z","steps":["trace[1571222049] 'agreement among raft nodes before linearized reading' (duration: 430.872313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:37.907681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:37.476706Z","time spent":"430.968916ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T21:39:37.907687Z","caller":"traceutil/trace.go:171","msg":"trace[391223141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2945; }","duration":"426.51206ms","start":"2026-07-25T21:39:37.481152Z","end":"2026-07-25T21:39:37.907664Z","steps":["trace[391223141] 'agreement among raft nodes before linearized reading' (duration: 426.331205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:37.907683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.546145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-25T21:39:37.907734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:37.481142Z","time spent":"426.583911ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T21:39:37.907741Z","caller":"traceutil/trace.go:171","msg":"trace[1925274228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2945; }","duration":"427.640906ms","start":"2026-07-25T21:39:37.480085Z","end":"2026-07-25T21:39:37.907726Z","steps":["trace[1925274228] 'agreement among raft nodes before linearized reading' (duration: 427.510193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:37.907789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:37.480059Z","time spent":"427.722308ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T21:39:46.108142Z","caller":"traceutil/trace.go:171","msg":"trace[1358394333] linearizableReadLoop","detail":"{readStateIndex:3132; appliedIndex:3131; }","duration":"148.015205ms","start":"2026-07-25T21:39:45.960103Z","end":"2026-07-25T21:39:46.108119Z","steps":["trace[1358394333] 'read index received' (duration: 147.856181ms)","trace[1358394333] 'applied index is now lower than readState.Index' (duration: 157.754µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:39:46.10824Z","caller":"traceutil/trace.go:171","msg":"trace[751570013] transaction","detail":"{read_only:false; response_revision:2973; number_of_response:1; }","duration":"148.721262ms","start":"2026-07-25T21:39:45.959491Z","end":"2026-07-25T21:39:46.108212Z","steps":["trace[751570013] 'process raft request' (duration: 148.484917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:46.108325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.20869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T21:39:46.108354Z","caller":"traceutil/trace.go:171","msg":"trace[1028324225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2973; }","duration":"148.251522ms","start":"2026-07-25T21:39:45.960093Z","end":"2026-07-25T21:39:46.108345Z","steps":["trace[1028324225] 'agreement among raft nodes before linearized reading' (duration: 148.133129ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:46.113834Z","caller":"traceutil/trace.go:171","msg":"trace[630395240] transaction","detail":"{read_only:false; response_revision:2974; number_of_response:1; }","duration":"153.201659ms","start":"2026-07-25T21:39:45.960619Z","end":"2026-07-25T21:39:46.113821Z","steps":["trace[630395240] 'process raft request' (duration: 153.048216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:46.11386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.488286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-25T21:39:46.113899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.114516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:46.113944Z","caller":"traceutil/trace.go:171","msg":"trace[258838511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2974; }","duration":"153.545777ms","start":"2026-07-25T21:39:45.960355Z","end":"2026-07-25T21:39:46.1139Z","steps":["trace[258838511] 'agreement among raft nodes before linearized reading' (duration: 153.399194ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:46.113995Z","caller":"traceutil/trace.go:171","msg":"trace[2090960046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2974; }","duration":"111.23482ms","start":"2026-07-25T21:39:46.002725Z","end":"2026-07-25T21:39:46.11396Z","steps":["trace[2090960046] 'agreement among raft nodes before linearized reading' (duration: 111.103986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:46.114013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.135818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T21:39:46.114047Z","caller":"traceutil/trace.go:171","msg":"trace[1707031932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2974; }","duration":"153.198689ms","start":"2026-07-25T21:39:45.960841Z","end":"2026-07-25T21:39:46.11404Z","steps":["trace[1707031932] 'agreement among raft nodes before linearized reading' (duration: 153.033805ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:50.289041Z","caller":"traceutil/trace.go:171","msg":"trace[1616952329] linearizableReadLoop","detail":"{readStateIndex:3145; appliedIndex:3144; }","duration":"282.92116ms","start":"2026-07-25T21:39:50.006096Z","end":"2026-07-25T21:39:50.289017Z","steps":["trace[1616952329] 'read index received' (duration: 282.653834ms)","trace[1616952329] 'applied index is now lower than readState.Index' (duration: 266.266µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:50.289281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.481473ms","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-07-25T21:39:50.289333Z","caller":"traceutil/trace.go:171","msg":"trace[1851395688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2985; }","duration":"160.579675ms","start":"2026-07-25T21:39:50.128745Z","end":"2026-07-25T21:39:50.289325Z","steps":["trace[1851395688] 'agreement among raft nodes before linearized reading' (duration: 160.455452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:50.28933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.196476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T21:39:50.289403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.845855ms","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-07-25T21:39:50.289441Z","caller":"traceutil/trace.go:171","msg":"trace[1382151622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2985; }","duration":"283.37586ms","start":"2026-07-25T21:39:50.006039Z","end":"2026-07-25T21:39:50.289415Z","steps":["trace[1382151622] 'agreement among raft nodes before linearized reading' (duration: 283.128124ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:50.289488Z","caller":"traceutil/trace.go:171","msg":"trace[1067749820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2985; }","duration":"140.973529ms","start":"2026-07-25T21:39:50.148491Z","end":"2026-07-25T21:39:50.289464Z","steps":["trace[1067749820] 'agreement among raft nodes before linearized reading' (duration: 140.792224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:50.289617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.453469ms","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-07-25T21:39:50.289635Z","caller":"traceutil/trace.go:171","msg":"trace[856574702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2985; }","duration":"141.512331ms","start":"2026-07-25T21:39:50.148117Z","end":"2026-07-25T21:39:50.28963Z","steps":["trace[856574702] 'agreement among raft nodes before linearized reading' (duration: 141.435659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:50.289696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.455523ms","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-07-25T21:39:50.289708Z","caller":"traceutil/trace.go:171","msg":"trace[1384499832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2985; }","duration":"142.488084ms","start":"2026-07-25T21:39:50.147216Z","end":"2026-07-25T21:39:50.289704Z","steps":["trace[1384499832] 'agreement among raft nodes before linearized reading' (duration: 142.465084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:50.289801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.330438ms","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-07-25T21:39:50.289812Z","caller":"traceutil/trace.go:171","msg":"trace[75897745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2985; }","duration":"115.379629ms","start":"2026-07-25T21:39:50.174429Z","end":"2026-07-25T21:39:50.289808Z","steps":["trace[75897745] 'agreement among raft nodes before linearized reading' (duration: 115.327458ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:50.289972Z","caller":"traceutil/trace.go:171","msg":"trace[302999038] transaction","detail":"{read_only:false; response_revision:2985; number_of_response:1; }","duration":"284.727773ms","start":"2026-07-25T21:39:50.004414Z","end":"2026-07-25T21:39:50.289141Z","steps":["trace[302999038] 'process raft request' (duration: 284.420336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:54.50548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103949903026130,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T21:39:54.617629Z","caller":"traceutil/trace.go:171","msg":"trace[1734953262] transaction","detail":"{read_only:false; response_revision:3012; number_of_response:1; }","duration":"794.778791ms","start":"2026-07-25T21:39:53.822811Z","end":"2026-07-25T21:39:54.61759Z","steps":["trace[1734953262] 'process raft request' (duration: 794.551986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:54.617868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:53.822789Z","time spent":"794.940575ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:39:54.98924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.70943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:39:54.989663Z","caller":"traceutil/trace.go:171","msg":"trace[1947919220] transaction","detail":"{read_only:false; response_revision:3013; number_of_response:1; }","duration":"682.211251ms","start":"2026-07-25T21:39:54.307425Z","end":"2026-07-25T21:39:54.989636Z","steps":["trace[1947919220] 'process raft request' (duration: 565.01827ms)","trace[1947919220] 'compare' (duration: 116.324591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:54.989799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.307399Z","time spent":"682.325834ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:55.074879Z","caller":"traceutil/trace.go:171","msg":"trace[452118217] transaction","detail":"{read_only:false; response_revision:3016; number_of_response:1; }","duration":"445.075412ms","start":"2026-07-25T21:39:54.629778Z","end":"2026-07-25T21:39:55.074853Z","steps":["trace[452118217] 'process raft request' (duration: 444.98464ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:55.074954Z","caller":"traceutil/trace.go:171","msg":"trace[259399224] linearizableReadLoop","detail":"{readStateIndex:3174; appliedIndex:3171; }","duration":"1.07052572s","start":"2026-07-25T21:39:54.0044Z","end":"2026-07-25T21:39:55.074926Z","steps":["trace[259399224] 'read index received' (duration: 613.103624ms)","trace[259399224] 'applied index is now lower than readState.Index' (duration: 457.420796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:55.075014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.629753Z","time spent":"445.192265ms","remote":"127.0.0.1:40534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:39:55.075142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070746105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:55.075182Z","caller":"traceutil/trace.go:171","msg":"trace[1102131302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3016; }","duration":"1.070817367s","start":"2026-07-25T21:39:54.004352Z","end":"2026-07-25T21:39:55.075169Z","steps":["trace[1102131302] 'agreement among raft nodes before linearized reading' (duration: 1.070738345s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.07521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.004336Z","time spent":"1.070867798s","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T21:39:55.075225Z","caller":"traceutil/trace.go:171","msg":"trace[1630738393] transaction","detail":"{read_only:false; response_revision:3014; number_of_response:1; }","duration":"445.455781ms","start":"2026-07-25T21:39:54.629758Z","end":"2026-07-25T21:39:55.075214Z","steps":["trace[1630738393] 'process raft request' (duration: 444.798495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.075297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.629736Z","time spent":"445.513103ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:39:55.075412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.867176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-25T21:39:55.075439Z","caller":"traceutil/trace.go:171","msg":"trace[96714084] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:3016; }","duration":"923.929848ms","start":"2026-07-25T21:39:54.1515Z","end":"2026-07-25T21:39:55.07543Z","steps":["trace[96714084] 'agreement among raft nodes before linearized reading' (duration: 923.831926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.075467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.151479Z","time spent":"923.981789ms","remote":"127.0.0.1:40454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-25T21:39:55.075578Z","caller":"traceutil/trace.go:171","msg":"trace[1441836808] transaction","detail":"{read_only:false; response_revision:3015; number_of_response:1; }","duration":"445.784959ms","start":"2026-07-25T21:39:54.629777Z","end":"2026-07-25T21:39:55.075562Z","steps":["trace[1441836808] 'process raft request' (duration: 444.919668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.075622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.510141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-25T21:39:55.075659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.99268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T21:39:55.075655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.629753Z","time spent":"445.85954ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:55.075673Z","caller":"traceutil/trace.go:171","msg":"trace[1100620288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3016; }","duration":"743.594373ms","start":"2026-07-25T21:39:54.332065Z","end":"2026-07-25T21:39:55.075659Z","steps":["trace[1100620288] 'agreement among raft nodes before linearized reading' (duration: 743.44445ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:55.075686Z","caller":"traceutil/trace.go:171","msg":"trace[401981999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3016; }","duration":"746.054642ms","start":"2026-07-25T21:39:54.329623Z","end":"2026-07-25T21:39:55.075678Z","steps":["trace[401981999] 'agreement among raft nodes before linearized reading' (duration: 745.9754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.075712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.329607Z","time spent":"746.099273ms","remote":"127.0.0.1:40532","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-07-25T21:39:55.075715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.33205Z","time spent":"743.653434ms","remote":"127.0.0.1:40532","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-07-25T21:39:55.075909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.292771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T21:39:55.075934Z","caller":"traceutil/trace.go:171","msg":"trace[522303141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3016; }","duration":"747.332462ms","start":"2026-07-25T21:39:54.328594Z","end":"2026-07-25T21:39:55.075927Z","steps":["trace[522303141] 'agreement among raft nodes before linearized reading' (duration: 747.25871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.07593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.62591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T21:39:55.075959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.328587Z","time spent":"747.366552ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T21:39:55.07598Z","caller":"traceutil/trace.go:171","msg":"trace[1686702359] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:3016; }","duration":"182.713823ms","start":"2026-07-25T21:39:54.893247Z","end":"2026-07-25T21:39:55.075961Z","steps":["trace[1686702359] 'agreement among raft nodes before linearized reading' (duration: 182.641121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.076106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.756676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T21:39:55.07613Z","caller":"traceutil/trace.go:171","msg":"trace[1057976116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3016; }","duration":"748.799477ms","start":"2026-07-25T21:39:54.327323Z","end":"2026-07-25T21:39:55.076122Z","steps":["trace[1057976116] 'agreement among raft nodes before linearized reading' (duration: 748.733736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.076154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.327314Z","time spent":"748.834568ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T21:39:55.076214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.19473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:55.07626Z","caller":"traceutil/trace.go:171","msg":"trace[325515055] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:3016; }","duration":"716.286012ms","start":"2026-07-25T21:39:54.35996Z","end":"2026-07-25T21:39:55.076246Z","steps":["trace[325515055] 'agreement among raft nodes before linearized reading' (duration: 716.211471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.076315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-25T21:39:55.076309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.359938Z","time spent":"716.354615ms","remote":"127.0.0.1:41228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-25T21:39:55.076341Z","caller":"traceutil/trace.go:171","msg":"trace[1853729711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3016; }","duration":"753.219052ms","start":"2026-07-25T21:39:54.323114Z","end":"2026-07-25T21:39:55.076333Z","steps":["trace[1853729711] 'agreement among raft nodes before linearized reading' (duration: 753.13538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.076366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.323083Z","time spent":"753.276213ms","remote":"127.0.0.1:40532","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-07-25T21:39:55.076462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.899441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T21:39:55.076515Z","caller":"traceutil/trace.go:171","msg":"trace[2112608951] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:3016; }","duration":"919.979013ms","start":"2026-07-25T21:39:54.156518Z","end":"2026-07-25T21:39:55.076497Z","steps":["trace[2112608951] 'agreement among raft nodes before linearized reading' (duration: 919.900072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.07656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:54.156502Z","time spent":"920.043645ms","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-07-25T21:39:55.260827Z","caller":"traceutil/trace.go:171","msg":"trace[1826749656] transaction","detail":"{read_only:false; response_revision:3017; number_of_response:1; }","duration":"178.191365ms","start":"2026-07-25T21:39:55.082616Z","end":"2026-07-25T21:39:55.260808Z","steps":["trace[1826749656] 'process raft request' (duration: 84.194296ms)","trace[1826749656] 'compare' (duration: 93.911197ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:39:55.329394Z","caller":"traceutil/trace.go:171","msg":"trace[390156800] transaction","detail":"{read_only:false; response_revision:3019; number_of_response:1; }","duration":"245.487567ms","start":"2026-07-25T21:39:55.083889Z","end":"2026-07-25T21:39:55.329377Z","steps":["trace[390156800] 'process raft request' (duration: 245.447836ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:55.329432Z","caller":"traceutil/trace.go:171","msg":"trace[972559159] transaction","detail":"{read_only:false; response_revision:3018; number_of_response:1; }","duration":"245.711642ms","start":"2026-07-25T21:39:55.08368Z","end":"2026-07-25T21:39:55.329391Z","steps":["trace[972559159] 'process raft request' (duration: 245.572299ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:55.329647Z","caller":"traceutil/trace.go:171","msg":"trace[1847236413] transaction","detail":"{read_only:false; response_revision:3020; number_of_response:1; }","duration":"243.968051ms","start":"2026-07-25T21:39:55.085672Z","end":"2026-07-25T21:39:55.32964Z","steps":["trace[1847236413] 'process raft request' (duration: 243.682795ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:55.329725Z","caller":"traceutil/trace.go:171","msg":"trace[1074896906] linearizableReadLoop","detail":"{readStateIndex:3181; appliedIndex:3177; }","duration":"243.010368ms","start":"2026-07-25T21:39:55.086707Z","end":"2026-07-25T21:39:55.329717Z","steps":["trace[1074896906] 'read index received' (duration: 80.071127ms)","trace[1074896906] 'applied index is now lower than readState.Index' (duration: 162.938501ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:39:55.329702Z","caller":"traceutil/trace.go:171","msg":"trace[892140793] transaction","detail":"{read_only:false; response_revision:3021; number_of_response:1; }","duration":"242.837104ms","start":"2026-07-25T21:39:55.086843Z","end":"2026-07-25T21:39:55.32968Z","steps":["trace[892140793] 'process raft request' (duration: 242.712781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:55.329778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.059709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:55.329793Z","caller":"traceutil/trace.go:171","msg":"trace[1412655544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3022; }","duration":"243.105291ms","start":"2026-07-25T21:39:55.086684Z","end":"2026-07-25T21:39:55.329789Z","steps":["trace[1412655544] 'agreement among raft nodes before linearized reading' (duration: 243.05604ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:55.329931Z","caller":"traceutil/trace.go:171","msg":"trace[1345544664] transaction","detail":"{read_only:false; response_revision:3022; number_of_response:1; }","duration":"242.342113ms","start":"2026-07-25T21:39:55.087559Z","end":"2026-07-25T21:39:55.329901Z","steps":["trace[1345544664] 'process raft request' (duration: 242.082056ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:55.452679Z","caller":"traceutil/trace.go:171","msg":"trace[720192791] transaction","detail":"{read_only:false; response_revision:3023; number_of_response:1; }","duration":"115.100642ms","start":"2026-07-25T21:39:55.337553Z","end":"2026-07-25T21:39:55.452653Z","steps":["trace[720192791] 'process raft request' (duration: 114.927028ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:55.667704Z","caller":"traceutil/trace.go:171","msg":"trace[84052717] transaction","detail":"{read_only:false; response_revision:3024; number_of_response:1; }","duration":"105.156675ms","start":"2026-07-25T21:39:55.562521Z","end":"2026-07-25T21:39:55.667678Z","steps":["trace[84052717] 'process raft request' (duration: 62.96562ms)","trace[84052717] 'compare' (duration: 41.96708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:56.156824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.266165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:56.156922Z","caller":"traceutil/trace.go:171","msg":"trace[794388509] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:3024; }","duration":"107.408309ms","start":"2026-07-25T21:39:56.049488Z","end":"2026-07-25T21:39:56.156896Z","steps":["trace[794388509] 'count revisions from in-memory index tree' (duration: 107.185004ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:57.224718Z","caller":"traceutil/trace.go:171","msg":"trace[1626433020] linearizableReadLoop","detail":"{readStateIndex:3186; appliedIndex:3185; }","duration":"222.783037ms","start":"2026-07-25T21:39:57.001874Z","end":"2026-07-25T21:39:57.224657Z","steps":["trace[1626433020] 'read index received' (duration: 222.48914ms)","trace[1626433020] 'applied index is now lower than readState.Index' (duration: 292.627µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:39:57.224941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.049513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:39:57.225025Z","caller":"traceutil/trace.go:171","msg":"trace[1676788996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3025; }","duration":"223.185227ms","start":"2026-07-25T21:39:57.001824Z","end":"2026-07-25T21:39:57.22501Z","steps":["trace[1676788996] 'agreement among raft nodes before linearized reading' (duration: 222.999942ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:39:57.225095Z","caller":"traceutil/trace.go:171","msg":"trace[1396080607] transaction","detail":"{read_only:false; response_revision:3025; number_of_response:1; }","duration":"230.47086ms","start":"2026-07-25T21:39:56.994587Z","end":"2026-07-25T21:39:57.225058Z","steps":["trace[1396080607] 'process raft request' (duration: 229.807184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:57.669079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.077579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T21:39:57.669142Z","caller":"traceutil/trace.go:171","msg":"trace[2018793355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3025; }","duration":"405.255244ms","start":"2026-07-25T21:39:57.263871Z","end":"2026-07-25T21:39:57.669126Z","steps":["trace[2018793355] 'range keys from in-memory index tree' (duration: 404.931636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:57.669174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.263849Z","time spent":"405.317475ms","remote":"127.0.0.1:40532","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-07-25T21:39:57.669703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.542447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T21:39:57.669733Z","caller":"traceutil/trace.go:171","msg":"trace[1077486814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3025; }","duration":"336.618958ms","start":"2026-07-25T21:39:57.333106Z","end":"2026-07-25T21:39:57.669725Z","steps":["trace[1077486814] 'range keys from in-memory index tree' (duration: 336.459945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:57.669711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.034425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-25T21:39:57.669752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.33308Z","time spent":"336.66728ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T21:39:57.669776Z","caller":"traceutil/trace.go:171","msg":"trace[482008304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3025; }","duration":"336.129987ms","start":"2026-07-25T21:39:57.333629Z","end":"2026-07-25T21:39:57.669759Z","steps":["trace[482008304] 'range keys from in-memory index tree' (duration: 335.900562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:57.669823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.333623Z","time spent":"336.190708ms","remote":"127.0.0.1:40532","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-07-25T21:39:57.670023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.525606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T21:39:57.670062Z","caller":"traceutil/trace.go:171","msg":"trace[660883934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3025; }","duration":"336.599688ms","start":"2026-07-25T21:39:57.333451Z","end":"2026-07-25T21:39:57.670051Z","steps":["trace[660883934] 'range keys from in-memory index tree' (duration: 336.381623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:57.670087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.33343Z","time spent":"336.65093ms","remote":"127.0.0.1:40532","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-07-25T21:39:57.670642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.515186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T21:39:57.670702Z","caller":"traceutil/trace.go:171","msg":"trace[1510075606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3025; }","duration":"337.000548ms","start":"2026-07-25T21:39:57.333683Z","end":"2026-07-25T21:39:57.670683Z","steps":["trace[1510075606] 'range keys from in-memory index tree' (duration: 336.352603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:57.670927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.333673Z","time spent":"337.1057ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T21:39:58.05834Z","caller":"traceutil/trace.go:171","msg":"trace[1618521934] transaction","detail":"{read_only:false; response_revision:3030; number_of_response:1; }","duration":"384.517539ms","start":"2026-07-25T21:39:57.673796Z","end":"2026-07-25T21:39:58.058313Z","steps":["trace[1618521934] 'process raft request' (duration: 384.469968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:58.058513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.673786Z","time spent":"384.648032ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:58.058918Z","caller":"traceutil/trace.go:171","msg":"trace[152448208] transaction","detail":"{read_only:false; response_revision:3027; number_of_response:1; }","duration":"385.301847ms","start":"2026-07-25T21:39:57.673605Z","end":"2026-07-25T21:39:58.058907Z","steps":["trace[152448208] 'process raft request' (duration: 384.434426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:58.058983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.673585Z","time spent":"385.367749ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:58.05912Z","caller":"traceutil/trace.go:171","msg":"trace[1096011672] transaction","detail":"{read_only:false; response_revision:3028; number_of_response:1; }","duration":"385.456111ms","start":"2026-07-25T21:39:57.673654Z","end":"2026-07-25T21:39:58.059111Z","steps":["trace[1096011672] 'process raft request' (duration: 384.539579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:58.059173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.673634Z","time spent":"385.512992ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:39:58.059302Z","caller":"traceutil/trace.go:171","msg":"trace[1865044954] transaction","detail":"{read_only:false; response_revision:3029; number_of_response:1; }","duration":"385.505502ms","start":"2026-07-25T21:39:57.673788Z","end":"2026-07-25T21:39:58.059294Z","steps":["trace[1865044954] 'process raft request' (duration: 384.444837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:39:58.059364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:57.673783Z","time spent":"385.551083ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:39:59.655992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:59.023303Z","time spent":"632.684441ms","remote":"127.0.0.1:40306","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-25T21:40:00.241037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103949903026170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T21:40:00.74191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103949903026170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T21:40:01.141948Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.486017288s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-25T21:40:01.513535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.234142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:40:01.513664Z","caller":"traceutil/trace.go:171","msg":"trace[1625692542] transaction","detail":"{read_only:false; response_revision:3031; number_of_response:1; }","duration":"2.283443253s","start":"2026-07-25T21:39:59.230199Z","end":"2026-07-25T21:40:01.513642Z","steps":["trace[1625692542] 'process raft request' (duration: 1.911998295s)","trace[1625692542] 'compare' (duration: 370.72134ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:40:01.513736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:59.230176Z","time spent":"2.283530284s","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:40:01.559445Z","caller":"traceutil/trace.go:171","msg":"trace[99926583] linearizableReadLoop","detail":"{readStateIndex:3195; appliedIndex:3192; }","duration":"1.818705772s","start":"2026-07-25T21:39:59.740694Z","end":"2026-07-25T21:40:01.5594Z","steps":["trace[99926583] 'read index received' (duration: 1.401525475s)","trace[99926583] 'applied index is now lower than readState.Index' (duration: 417.175236ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:40:01.559606Z","caller":"traceutil/trace.go:171","msg":"trace[1952681355] transaction","detail":"{read_only:false; response_revision:3032; number_of_response:1; }","duration":"1.902516399s","start":"2026-07-25T21:39:59.657074Z","end":"2026-07-25T21:40:01.559591Z","steps":["trace[1952681355] 'process raft request' (duration: 1.902078189s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.559772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:59.657055Z","time spent":"1.902598391s","remote":"127.0.0.1:40306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:40:01.560074Z","caller":"traceutil/trace.go:171","msg":"trace[1068303662] transaction","detail":"{read_only:false; response_revision:3033; number_of_response:1; }","duration":"1.88918128s","start":"2026-07-25T21:39:59.670881Z","end":"2026-07-25T21:40:01.560062Z","steps":["trace[1068303662] 'process raft request' (duration: 1.888453433s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.560136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:59.670862Z","time spent":"1.889246252s","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T21:40:01.560371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819672206s","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-07-25T21:40:01.560413Z","caller":"traceutil/trace.go:171","msg":"trace[1495737790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3033; }","duration":"1.819748027s","start":"2026-07-25T21:39:59.740656Z","end":"2026-07-25T21:40:01.560404Z","steps":["trace[1495737790] 'agreement among raft nodes before linearized reading' (duration: 1.819641624s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.560443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:39:59.740636Z","time spent":"1.819800188s","remote":"127.0.0.1:40532","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-07-25T21:40:01.560621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.555542483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:40:01.560652Z","caller":"traceutil/trace.go:171","msg":"trace[633949824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3033; }","duration":"1.555612995s","start":"2026-07-25T21:40:00.005031Z","end":"2026-07-25T21:40:01.560644Z","steps":["trace[633949824] 'agreement among raft nodes before linearized reading' (duration: 1.555564224s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.56068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:00.005009Z","time spent":"1.555666467s","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T21:40:01.561061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.757621ms","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-07-25T21:40:01.561102Z","caller":"traceutil/trace.go:171","msg":"trace[593677249] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:3033; }","duration":"561.841213ms","start":"2026-07-25T21:40:00.99925Z","end":"2026-07-25T21:40:01.561091Z","steps":["trace[593677249] 'agreement among raft nodes before linearized reading' (duration: 561.785312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.56113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:00.999224Z","time spent":"561.898644ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-25T21:40:01.561287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496947517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T21:40:01.561319Z","caller":"traceutil/trace.go:171","msg":"trace[1736259272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3033; }","duration":"1.496993219s","start":"2026-07-25T21:40:00.064318Z","end":"2026-07-25T21:40:01.561312Z","steps":["trace[1736259272] 'agreement among raft nodes before linearized reading' (duration: 1.496935348s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.561347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:00.064312Z","time spent":"1.49702684s","remote":"127.0.0.1:40532","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-07-25T21:40:01.561482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497615133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T21:40:01.561512Z","caller":"traceutil/trace.go:171","msg":"trace[1921734461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3033; }","duration":"1.497658585s","start":"2026-07-25T21:40:00.063846Z","end":"2026-07-25T21:40:01.561505Z","steps":["trace[1921734461] 'agreement among raft nodes before linearized reading' (duration: 1.497609044s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.561537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:00.063839Z","time spent":"1.497692395s","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T21:40:01.561695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49787242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T21:40:01.561726Z","caller":"traceutil/trace.go:171","msg":"trace[1624045957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3033; }","duration":"1.497920271s","start":"2026-07-25T21:40:00.063799Z","end":"2026-07-25T21:40:01.561719Z","steps":["trace[1624045957] 'agreement among raft nodes before linearized reading' (duration: 1.49787107s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.561751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:00.063789Z","time spent":"1.497956422s","remote":"127.0.0.1:40532","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-07-25T21:40:01.561879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498211868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T21:40:01.56191Z","caller":"traceutil/trace.go:171","msg":"trace[323235388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3033; }","duration":"1.498272929s","start":"2026-07-25T21:40:00.063629Z","end":"2026-07-25T21:40:01.561902Z","steps":["trace[323235388] 'agreement among raft nodes before linearized reading' (duration: 1.498222148s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:01.564826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:00.063604Z","time spent":"1.501211389s","remote":"127.0.0.1:40532","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-07-25T21:40:02.070028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103949903026180,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T21:40:02.115695Z","caller":"traceutil/trace.go:171","msg":"trace[1675922952] transaction","detail":"{read_only:false; response_revision:3034; number_of_response:1; }","duration":"547.606324ms","start":"2026-07-25T21:40:01.568063Z","end":"2026-07-25T21:40:02.11567Z","steps":["trace[1675922952] 'process raft request' (duration: 547.44292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:02.115863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:01.568044Z","time spent":"547.731388ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:40:02.137882Z","caller":"traceutil/trace.go:171","msg":"trace[583703133] transaction","detail":"{read_only:false; response_revision:3035; number_of_response:1; }","duration":"569.721962ms","start":"2026-07-25T21:40:01.568135Z","end":"2026-07-25T21:40:02.137857Z","steps":["trace[583703133] 'process raft request' (duration: 563.482503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:02.138096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:01.568101Z","time spent":"569.896695ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:40:02.138206Z","caller":"traceutil/trace.go:171","msg":"trace[1311652006] transaction","detail":"{read_only:false; response_revision:3036; number_of_response:1; }","duration":"569.956687ms","start":"2026-07-25T21:40:01.568217Z","end":"2026-07-25T21:40:02.138174Z","steps":["trace[1311652006] 'process raft request' (duration: 569.591529ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:40:02.138293Z","caller":"traceutil/trace.go:171","msg":"trace[200153446] linearizableReadLoop","detail":"{readStateIndex:3201; appliedIndex:3195; }","duration":"568.755209ms","start":"2026-07-25T21:40:01.569519Z","end":"2026-07-25T21:40:02.138274Z","steps":["trace[200153446] 'read index received' (duration: 545.968076ms)","trace[200153446] 'applied index is now lower than readState.Index' (duration: 22.786223ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:40:02.138393Z","caller":"traceutil/trace.go:171","msg":"trace[1409117486] transaction","detail":"{read_only:false; response_revision:3037; number_of_response:1; }","duration":"570.10895ms","start":"2026-07-25T21:40:01.568278Z","end":"2026-07-25T21:40:02.138387Z","steps":["trace[1409117486] 'process raft request' (duration: 569.854454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:02.138367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:01.56821Z","time spent":"570.06746ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:40:02.138476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:01.568256Z","time spent":"570.157862ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:40:02.13859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.065726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-25T21:40:02.138618Z","caller":"traceutil/trace.go:171","msg":"trace[1952253132] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:3039; }","duration":"569.105007ms","start":"2026-07-25T21:40:01.569505Z","end":"2026-07-25T21:40:02.13861Z","steps":["trace[1952253132] 'agreement among raft nodes before linearized reading' (duration: 568.940873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:02.138646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:01.569498Z","time spent":"569.140137ms","remote":"127.0.0.1:40534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-07-25T21:40:02.138644Z","caller":"traceutil/trace.go:171","msg":"trace[546178953] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"569.63926ms","start":"2026-07-25T21:40:01.568977Z","end":"2026-07-25T21:40:02.138616Z","steps":["trace[546178953] 'process raft request' (duration: 569.24202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:02.138708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:01.568952Z","time spent":"569.718811ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:40:02.138754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.222169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:40:02.138775Z","caller":"traceutil/trace.go:171","msg":"trace[2006494819] transaction","detail":"{read_only:false; response_revision:3039; number_of_response:1; }","duration":"207.042432ms","start":"2026-07-25T21:40:01.931724Z","end":"2026-07-25T21:40:02.138766Z","steps":["trace[2006494819] 'process raft request' (duration: 206.516569ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:40:02.138779Z","caller":"traceutil/trace.go:171","msg":"trace[1011080655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3039; }","duration":"569.270781ms","start":"2026-07-25T21:40:01.569502Z","end":"2026-07-25T21:40:02.138773Z","steps":["trace[1011080655] 'agreement among raft nodes before linearized reading' (duration: 569.2313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:02.138855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:01.569492Z","time spent":"569.353602ms","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T21:40:12.010662Z","caller":"traceutil/trace.go:171","msg":"trace[1796594161] transaction","detail":"{read_only:false; response_revision:3111; number_of_response:1; }","duration":"311.297147ms","start":"2026-07-25T21:40:11.699328Z","end":"2026-07-25T21:40:12.010626Z","steps":["trace[1796594161] 'process raft request' (duration: 303.516753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:12.010886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:11.699275Z","time spent":"311.502161ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:40:17.523736Z","caller":"traceutil/trace.go:171","msg":"trace[1565013997] transaction","detail":"{read_only:false; response_revision:3137; number_of_response:1; }","duration":"160.137779ms","start":"2026-07-25T21:40:17.363559Z","end":"2026-07-25T21:40:17.523697Z","steps":["trace[1565013997] 'process raft request' (duration: 159.855275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:18.242021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.985724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:40:18.242267Z","caller":"traceutil/trace.go:171","msg":"trace[865899909] transaction","detail":"{read_only:false; response_revision:3138; number_of_response:1; }","duration":"714.122857ms","start":"2026-07-25T21:40:17.528116Z","end":"2026-07-25T21:40:18.242239Z","steps":["trace[865899909] 'process raft request' (duration: 466.851399ms)","trace[865899909] 'compare' (duration: 246.818131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:40:18.242435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:17.5281Z","time spent":"714.251479ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T21:40:18.242565Z","caller":"traceutil/trace.go:171","msg":"trace[399729614] linearizableReadLoop","detail":"{readStateIndex:3304; appliedIndex:3302; }","duration":"238.889985ms","start":"2026-07-25T21:40:18.003665Z","end":"2026-07-25T21:40:18.242555Z","steps":["trace[399729614] 'read index received' (duration: 27.767277ms)","trace[399729614] 'applied index is now lower than readState.Index' (duration: 211.121968ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:40:18.242644Z","caller":"traceutil/trace.go:171","msg":"trace[1669604454] transaction","detail":"{read_only:false; response_revision:3139; number_of_response:1; }","duration":"494.083599ms","start":"2026-07-25T21:40:17.74854Z","end":"2026-07-25T21:40:18.242623Z","steps":["trace[1669604454] 'process raft request' (duration: 493.554101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:18.242696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.027297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:40:18.242718Z","caller":"traceutil/trace.go:171","msg":"trace[1918762071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3139; }","duration":"239.080017ms","start":"2026-07-25T21:40:18.003632Z","end":"2026-07-25T21:40:18.242712Z","steps":["trace[1918762071] 'agreement among raft nodes before linearized reading' (duration: 239.021696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:18.242754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:40:17.748517Z","time spent":"494.17522ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:40:18.242898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.852464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-25T21:40:18.243075Z","caller":"traceutil/trace.go:171","msg":"trace[1631885032] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:3139; }","duration":"208.949405ms","start":"2026-07-25T21:40:18.033985Z","end":"2026-07-25T21:40:18.242934Z","steps":["trace[1631885032] 'agreement among raft nodes before linearized reading' (duration: 208.798393ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:40:18.488047Z","caller":"traceutil/trace.go:171","msg":"trace[1089117086] transaction","detail":"{read_only:false; response_revision:3140; number_of_response:1; }","duration":"241.834608ms","start":"2026-07-25T21:40:18.246194Z","end":"2026-07-25T21:40:18.488028Z","steps":["trace[1089117086] 'process raft request' (duration: 221.074873ms)","trace[1089117086] 'compare' (duration: 20.682984ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:40:18.494183Z","caller":"traceutil/trace.go:171","msg":"trace[1669815692] transaction","detail":"{read_only:false; response_revision:3141; number_of_response:1; }","duration":"247.215897ms","start":"2026-07-25T21:40:18.246957Z","end":"2026-07-25T21:40:18.494173Z","steps":["trace[1669815692] 'process raft request' (duration: 247.134376ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:40:18.494489Z","caller":"traceutil/trace.go:171","msg":"trace[1822411117] linearizableReadLoop","detail":"{readStateIndex:3306; appliedIndex:3304; }","duration":"231.667409ms","start":"2026-07-25T21:40:18.262473Z","end":"2026-07-25T21:40:18.49414Z","steps":["trace[1822411117] 'read index received' (duration: 204.966017ms)","trace[1822411117] 'applied index is now lower than readState.Index' (duration: 26.698442ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:40:18.494702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.232998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T21:40:18.494763Z","caller":"traceutil/trace.go:171","msg":"trace[1625778936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3141; }","duration":"168.32144ms","start":"2026-07-25T21:40:18.326432Z","end":"2026-07-25T21:40:18.494754Z","steps":["trace[1625778936] 'agreement among raft nodes before linearized reading' (duration: 168.217148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:40:18.494986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.564699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T21:40:18.495055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.992704ms","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-07-25T21:40:18.495059Z","caller":"traceutil/trace.go:171","msg":"trace[1784414219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3141; }","duration":"195.65659ms","start":"2026-07-25T21:40:18.299395Z","end":"2026-07-25T21:40:18.495051Z","steps":["trace[1784414219] 'agreement among raft nodes before linearized reading' (duration: 195.188043ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:40:18.49509Z","caller":"traceutil/trace.go:171","msg":"trace[1926270439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3141; }","duration":"232.643814ms","start":"2026-07-25T21:40:18.262435Z","end":"2026-07-25T21:40:18.495079Z","steps":["trace[1926270439] 'agreement among raft nodes before linearized reading' (duration: 231.790521ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:40:50.935535Z","caller":"traceutil/trace.go:171","msg":"trace[1365803423] transaction","detail":"{read_only:false; response_revision:3292; number_of_response:1; }","duration":"101.190315ms","start":"2026-07-25T21:40:50.834315Z","end":"2026-07-25T21:40:50.935505Z","steps":["trace[1365803423] 'process raft request' (duration: 76.971759ms)","trace[1365803423] 'compare' (duration: 24.047211ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:41:23.414822Z","caller":"traceutil/trace.go:171","msg":"trace[1223818311] transaction","detail":"{read_only:false; response_revision:3439; number_of_response:1; }","duration":"104.210265ms","start":"2026-07-25T21:41:23.310581Z","end":"2026-07-25T21:41:23.414791Z","steps":["trace[1223818311] 'process raft request' (duration: 96.41583ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:41:23.416978Z","caller":"traceutil/trace.go:171","msg":"trace[933314276] transaction","detail":"{read_only:false; response_revision:3440; number_of_response:1; }","duration":"106.376576ms","start":"2026-07-25T21:41:23.310585Z","end":"2026-07-25T21:41:23.416962Z","steps":["trace[933314276] 'process raft request' (duration: 105.983427ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:41:46.51418Z","caller":"traceutil/trace.go:171","msg":"trace[1580655669] transaction","detail":"{read_only:false; response_revision:3543; number_of_response:1; }","duration":"116.388824ms","start":"2026-07-25T21:41:46.397761Z","end":"2026-07-25T21:41:46.51415Z","steps":["trace[1580655669] 'process raft request' (duration: 116.23657ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:43:18.361049Z","caller":"traceutil/trace.go:171","msg":"trace[1832059409] transaction","detail":"{read_only:false; response_revision:3965; number_of_response:1; }","duration":"149.104399ms","start":"2026-07-25T21:43:18.211912Z","end":"2026-07-25T21:43:18.361016Z","steps":["trace[1832059409] 'process raft request' (duration: 121.945305ms)","trace[1832059409] 'compare' (duration: 27.052992ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:43:19.20515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.730684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-25T21:43:19.205375Z","caller":"traceutil/trace.go:171","msg":"trace[377294569] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:3966; }","duration":"171.00167ms","start":"2026-07-25T21:43:19.034358Z","end":"2026-07-25T21:43:19.205359Z","steps":["trace[377294569] 'range keys from in-memory index tree' (duration: 170.612051ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:43:20.026694Z","caller":"traceutil/trace.go:171","msg":"trace[1864201560] linearizableReadLoop","detail":"{readStateIndex:4172; appliedIndex:4171; }","duration":"199.338032ms","start":"2026-07-25T21:43:19.827336Z","end":"2026-07-25T21:43:20.026674Z","steps":["trace[1864201560] 'read index received' (duration: 199.260911ms)","trace[1864201560] 'applied index is now lower than readState.Index' (duration: 75.991µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:43:20.02705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.67618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T21:43:20.027206Z","caller":"traceutil/trace.go:171","msg":"trace[1521574824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3970; }","duration":"199.900076ms","start":"2026-07-25T21:43:19.827273Z","end":"2026-07-25T21:43:20.027173Z","steps":["trace[1521574824] 'agreement among raft nodes before linearized reading' (duration: 199.507377ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:43:20.027767Z","caller":"traceutil/trace.go:171","msg":"trace[940538196] transaction","detail":"{read_only:false; response_revision:3970; number_of_response:1; }","duration":"232.164462ms","start":"2026-07-25T21:43:19.795581Z","end":"2026-07-25T21:43:20.027746Z","steps":["trace[940538196] 'process raft request' (duration: 230.962143ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:43:20.489059Z","caller":"traceutil/trace.go:171","msg":"trace[2124008020] transaction","detail":"{read_only:false; response_revision:3976; number_of_response:1; }","duration":"119.877206ms","start":"2026-07-25T21:43:20.369146Z","end":"2026-07-25T21:43:20.489024Z","steps":["trace[2124008020] 'process raft request' (duration: 116.277501ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:43:22.203352Z","caller":"traceutil/trace.go:171","msg":"trace[703118508] transaction","detail":"{read_only:false; response_revision:3981; number_of_response:1; }","duration":"112.351977ms","start":"2026-07-25T21:43:22.09097Z","end":"2026-07-25T21:43:22.203322Z","steps":["trace[703118508] 'process raft request' (duration: 71.829185ms)","trace[703118508] 'compare' (duration: 40.390879ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:43:24.095451Z","caller":"traceutil/trace.go:171","msg":"trace[287572680] transaction","detail":"{read_only:false; response_revision:3987; number_of_response:1; }","duration":"147.473481ms","start":"2026-07-25T21:43:23.947925Z","end":"2026-07-25T21:43:24.095398Z","steps":["trace[287572680] 'process raft request' (duration: 147.326138ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:43:27.172602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2668} {"level":"info","ts":"2026-07-25T21:43:27.172637Z","caller":"traceutil/trace.go:171","msg":"trace[1790501680] compact","detail":"{revision:2668; response_revision:4003; }","duration":"159.887213ms","start":"2026-07-25T21:43:27.012724Z","end":"2026-07-25T21:43:27.172611Z","steps":["trace[1790501680] 'process raft request' (duration: 68.907309ms)","trace[1790501680] 'check and update compact revision' (duration: 90.889622ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:43:27.173466Z","caller":"traceutil/trace.go:171","msg":"trace[1431206585] transaction","detail":"{read_only:false; response_revision:4004; number_of_response:1; }","duration":"157.514757ms","start":"2026-07-25T21:43:27.015929Z","end":"2026-07-25T21:43:27.173444Z","steps":["trace[1431206585] 'process raft request' (duration: 157.052906ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:43:27.281332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2668,"took":"107.927488ms","hash":1808356516,"current-db-size-bytes":23433216,"current-db-size":"23 MB","current-db-size-in-use-bytes":6098944,"current-db-size-in-use":"6.1 MB"} {"level":"info","ts":"2026-07-25T21:43:27.281386Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1808356516,"revision":2668,"compact-revision":1684} {"level":"info","ts":"2026-07-25T21:43:29.642723Z","caller":"traceutil/trace.go:171","msg":"trace[226016372] transaction","detail":"{read_only:false; response_revision:4015; number_of_response:1; }","duration":"240.961202ms","start":"2026-07-25T21:43:29.401737Z","end":"2026-07-25T21:43:29.642698Z","steps":["trace[226016372] 'process raft request' (duration: 240.791718ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:43:38.64206Z","caller":"traceutil/trace.go:171","msg":"trace[1162635481] transaction","detail":"{read_only:false; response_revision:4056; number_of_response:1; }","duration":"102.082409ms","start":"2026-07-25T21:43:38.539937Z","end":"2026-07-25T21:43:38.642019Z","steps":["trace[1162635481] 'process raft request' (duration: 35.73085ms)","trace[1162635481] 'compare' (duration: 66.087483ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:44:10.80367Z","caller":"traceutil/trace.go:171","msg":"trace[1061833845] transaction","detail":"{read_only:false; response_revision:4203; number_of_response:1; }","duration":"106.182236ms","start":"2026-07-25T21:44:10.697444Z","end":"2026-07-25T21:44:10.803627Z","steps":["trace[1061833845] 'process raft request' (duration: 106.035712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:44:10.997297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.544709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T21:44:10.997418Z","caller":"traceutil/trace.go:171","msg":"trace[775739030] linearizableReadLoop","detail":"{readStateIndex:4417; appliedIndex:4416; }","duration":"174.075542ms","start":"2026-07-25T21:44:10.823325Z","end":"2026-07-25T21:44:10.9974Z","steps":["trace[775739030] 'read index received' (duration: 42.337638ms)","trace[775739030] 'applied index is now lower than readState.Index' (duration: 131.736874ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:44:10.997453Z","caller":"traceutil/trace.go:171","msg":"trace[832407614] transaction","detail":"{read_only:false; response_revision:4204; number_of_response:1; }","duration":"189.983771ms","start":"2026-07-25T21:44:10.807448Z","end":"2026-07-25T21:44:10.997432Z","steps":["trace[832407614] 'process raft request' (duration: 58.225146ms)","trace[832407614] 'compare' (duration: 131.397226ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:44:10.99759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.268676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T21:44:10.997628Z","caller":"traceutil/trace.go:171","msg":"trace[1291532539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4204; }","duration":"174.333738ms","start":"2026-07-25T21:44:10.823284Z","end":"2026-07-25T21:44:10.997618Z","steps":["trace[1291532539] 'agreement among raft nodes before linearized reading' (duration: 174.207515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:44:10.997782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.160081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6772"} {"level":"info","ts":"2026-07-25T21:44:10.997817Z","caller":"traceutil/trace.go:171","msg":"trace[941032772] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4204; }","duration":"147.233273ms","start":"2026-07-25T21:44:10.850573Z","end":"2026-07-25T21:44:10.997806Z","steps":["trace[941032772] 'agreement among raft nodes before linearized reading' (duration: 147.146291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:44:10.998453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.687473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T21:44:10.998599Z","caller":"traceutil/trace.go:171","msg":"trace[800787672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4204; }","duration":"168.861998ms","start":"2026-07-25T21:44:10.829712Z","end":"2026-07-25T21:44:10.998574Z","steps":["trace[800787672] 'agreement among raft nodes before linearized reading' (duration: 167.858004ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:44:20.935105Z","caller":"traceutil/trace.go:171","msg":"trace[1962620804] transaction","detail":"{read_only:false; response_revision:4248; number_of_response:1; }","duration":"215.727692ms","start":"2026-07-25T21:44:20.719363Z","end":"2026-07-25T21:44:20.93509Z","steps":["trace[1962620804] 'process raft request' (duration: 215.447615ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:44:43.219522Z","caller":"traceutil/trace.go:171","msg":"trace[679924389] transaction","detail":"{read_only:false; response_revision:4349; number_of_response:1; }","duration":"173.902504ms","start":"2026-07-25T21:44:43.045593Z","end":"2026-07-25T21:44:43.219496Z","steps":["trace[679924389] 'process raft request' (duration: 173.72362ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:44:43.221346Z","caller":"traceutil/trace.go:171","msg":"trace[1570129278] transaction","detail":"{read_only:false; response_revision:4350; number_of_response:1; }","duration":"174.52445ms","start":"2026-07-25T21:44:43.046799Z","end":"2026-07-25T21:44:43.221324Z","steps":["trace[1570129278] 'process raft request' (duration: 174.147911ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:44:57.257958Z","caller":"traceutil/trace.go:171","msg":"trace[822586454] transaction","detail":"{read_only:false; response_revision:4412; number_of_response:1; }","duration":"215.762659ms","start":"2026-07-25T21:44:57.042147Z","end":"2026-07-25T21:44:57.25791Z","steps":["trace[822586454] 'process raft request' (duration: 215.587355ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:45:34.820151Z","caller":"traceutil/trace.go:171","msg":"trace[90215588] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"128.891751ms","start":"2026-07-25T21:45:34.691225Z","end":"2026-07-25T21:45:34.820117Z","steps":["trace[90215588] 'process raft request' (duration: 128.339968ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:45:40.172552Z","caller":"traceutil/trace.go:171","msg":"trace[1198183805] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"160.512144ms","start":"2026-07-25T21:45:40.012014Z","end":"2026-07-25T21:45:40.172527Z","steps":["trace[1198183805] 'process raft request' (duration: 159.91649ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:45:42.463028Z","caller":"traceutil/trace.go:171","msg":"trace[1156502542] linearizableReadLoop","detail":"{readStateIndex:4879; appliedIndex:4878; }","duration":"285.956904ms","start":"2026-07-25T21:45:42.177049Z","end":"2026-07-25T21:45:42.463005Z","steps":["trace[1156502542] 'read index received' (duration: 285.81247ms)","trace[1156502542] 'applied index is now lower than readState.Index' (duration: 143.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:45:42.463097Z","caller":"traceutil/trace.go:171","msg":"trace[2054598646] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"416.174096ms","start":"2026-07-25T21:45:42.046892Z","end":"2026-07-25T21:45:42.463066Z","steps":["trace[2054598646] 'process raft request' (duration: 415.980431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:45:42.463195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.11293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T21:45:42.463236Z","caller":"traceutil/trace.go:171","msg":"trace[1611650756] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4647; }","duration":"206.211383ms","start":"2026-07-25T21:45:42.257014Z","end":"2026-07-25T21:45:42.463226Z","steps":["trace[1611650756] 'agreement among raft nodes before linearized reading' (duration: 206.130061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T21:45:42.463263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T21:45:42.046868Z","time spent":"416.305369ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T21:45:42.46391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.844524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T21:45:42.464055Z","caller":"traceutil/trace.go:171","msg":"trace[1926260123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4647; }","duration":"287.035279ms","start":"2026-07-25T21:45:42.176995Z","end":"2026-07-25T21:45:42.464031Z","steps":["trace[1926260123] 'agreement among raft nodes before linearized reading' (duration: 286.194639ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:45:42.573568Z","caller":"traceutil/trace.go:171","msg":"trace[906203929] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"101.459767ms","start":"2026-07-25T21:45:42.472071Z","end":"2026-07-25T21:45:42.57353Z","steps":["trace[906203929] 'process raft request' (duration: 101.349444ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:45:42.573609Z","caller":"traceutil/trace.go:171","msg":"trace[739262261] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4879; }","duration":"102.184445ms","start":"2026-07-25T21:45:42.471385Z","end":"2026-07-25T21:45:42.57357Z","steps":["trace[739262261] 'read index received' (duration: 52.677495ms)","trace[739262261] 'applied index is now lower than readState.Index' (duration: 49.50454ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T21:45:42.573624Z","caller":"traceutil/trace.go:171","msg":"trace[1082202833] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"104.035779ms","start":"2026-07-25T21:45:42.469538Z","end":"2026-07-25T21:45:42.573574Z","steps":["trace[1082202833] 'process raft request' (duration: 54.334755ms)","trace[1082202833] 'compare' (duration: 49.351636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T21:45:42.573728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.334908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T21:45:42.57376Z","caller":"traceutil/trace.go:171","msg":"trace[901194029] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:4649; }","duration":"102.41532ms","start":"2026-07-25T21:45:42.471334Z","end":"2026-07-25T21:45:42.57375Z","steps":["trace[901194029] 'agreement among raft nodes before linearized reading' (duration: 102.342098ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T21:45:45.105376Z","caller":"traceutil/trace.go:171","msg":"trace[1519146817] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"141.019749ms","start":"2026-07-25T21:45:44.964333Z","end":"2026-07-25T21:45:45.105353Z","steps":["trace[1519146817] 'process raft request' (duration: 140.848075ms)"],"step_count":1}