{"level":"warn","ts":"2026-05-28T21:44:41.583758Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:44:41.583869Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-28T21:44:41.583941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:44:41.583953Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-28T21:44:41.583975Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T21:44:41.584759Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-28T21:44:41.584842Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-28T21:44:41.589922Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.8749ms"} {"level":"info","ts":"2026-05-28T21:44:41.597788Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-28T21:44:41.597931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T21:44:41.597977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-28T21:44:41.598013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T21:44:41.598038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-28T21:44:41.598116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-28T21:44:41.604772Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T21:44:41.606402Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T21:44:41.608305Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-28T21:44:41.610877Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T21:44:41.612236Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T21:44:41.612338Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T21:44:41.612512Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T21:44:41.612568Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T21:44:41.613617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-28T21:44:41.613768Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-28T21:44:41.615281Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T21:44:41.615489Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T21:44:41.615523Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T21:44:41.615614Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-28T21:44:41.615629Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-28T21:44:42.199017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T21:44:42.199089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T21:44:42.199114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-28T21:44:42.199132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-28T21:44:42.199143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-28T21:44:42.199165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-28T21:44:42.199227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-28T21:44:42.201165Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:44:42.202215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:44:42.202217Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T21:44:42.202317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:44:42.202648Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T21:44:42.202709Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T21:44:42.205583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T21:44:42.207631Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-28T21:44:42.207824Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:44:42.208243Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:44:42.218171Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-28T21:44:43.969378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.874589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T21:44:43.969453Z","caller":"traceutil/trace.go:171","msg":"trace[903860384] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:69; }","duration":"251.007732ms","start":"2026-05-28T21:44:43.718431Z","end":"2026-05-28T21:44:43.969439Z","steps":["trace[903860384] 'range keys from in-memory index tree' (duration: 250.790616ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:44:43.969917Z","caller":"traceutil/trace.go:171","msg":"trace[848223132] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"180.06055ms","start":"2026-05-28T21:44:43.789833Z","end":"2026-05-28T21:44:43.969894Z","steps":["trace[848223132] 'process raft request' (duration: 179.883136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:48.481196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.875924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:44:48.481287Z","caller":"traceutil/trace.go:171","msg":"trace[1365052208] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:327; }","duration":"131.008986ms","start":"2026-05-28T21:44:48.350258Z","end":"2026-05-28T21:44:48.481267Z","steps":["trace[1365052208] 'range keys from in-memory index tree' (duration: 130.811951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:55.398866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399059965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:44:55.398958Z","caller":"traceutil/trace.go:171","msg":"trace[479000232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:398; }","duration":"1.399202319s","start":"2026-05-28T21:44:53.999736Z","end":"2026-05-28T21:44:55.398939Z","steps":["trace[479000232] 'range keys from in-memory index tree' (duration: 1.398998214s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:55.399001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:53.999718Z","time spent":"1.39927446s","remote":"127.0.0.1:38264","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-05-28T21:44:55.399429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373384205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:44:55.399481Z","caller":"traceutil/trace.go:171","msg":"trace[2141626770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:398; }","duration":"1.373458827s","start":"2026-05-28T21:44:54.026009Z","end":"2026-05-28T21:44:55.399468Z","steps":["trace[2141626770] 'range keys from in-memory index tree' (duration: 1.373322894s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:55.399516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:54.026Z","time spent":"1.373506459s","remote":"127.0.0.1:38522","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-05-28T21:44:55.400424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138156041s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:44:55.400556Z","caller":"traceutil/trace.go:171","msg":"trace[185781349] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"257.695287ms","start":"2026-05-28T21:44:55.142839Z","end":"2026-05-28T21:44:55.400534Z","steps":["trace[185781349] 'read index received' (duration: 56.862µs)","trace[185781349] 'applied index is now lower than readState.Index' (duration: 257.636935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:44:55.40077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.924022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T21:44:55.400835Z","caller":"traceutil/trace.go:171","msg":"trace[1868735400] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:399; }","duration":"258.054945ms","start":"2026-05-28T21:44:55.142764Z","end":"2026-05-28T21:44:55.400819Z","steps":["trace[1868735400] 'agreement among raft nodes before linearized reading' (duration: 257.952553ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:44:55.40093Z","caller":"traceutil/trace.go:171","msg":"trace[130340594] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.223114647s","start":"2026-05-28T21:44:54.177785Z","end":"2026-05-28T21:44:55.400899Z","steps":["trace[130340594] 'process raft request' (duration: 83.776167ms)","trace[130340594] 'compare' (duration: 1.137135546s)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:44:55.4011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:54.177759Z","time spent":"1.22325128s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:44:55.401199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.48673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T21:44:55.401242Z","caller":"traceutil/trace.go:171","msg":"trace[1071751322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"248.555652ms","start":"2026-05-28T21:44:55.152676Z","end":"2026-05-28T21:44:55.401231Z","steps":["trace[1071751322] 'agreement among raft nodes before linearized reading' (duration: 248.477081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:55.401397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.385821ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:44:55.401644Z","caller":"traceutil/trace.go:171","msg":"trace[104705510] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:399; }","duration":"209.675318ms","start":"2026-05-28T21:44:55.191935Z","end":"2026-05-28T21:44:55.40161Z","steps":["trace[104705510] 'agreement among raft nodes before linearized reading' (duration: 209.288608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:56.191077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.118777ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-28T21:44:56.191176Z","caller":"traceutil/trace.go:171","msg":"trace[1641255483] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"785.117694ms","start":"2026-05-28T21:44:55.406041Z","end":"2026-05-28T21:44:56.191158Z","steps":["trace[1641255483] 'read index received' (duration: 416.914885ms)","trace[1641255483] 'applied index is now lower than readState.Index' (duration: 368.201438ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:44:56.191195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:55.402859Z","time spent":"788.329462ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-28T21:44:56.191033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.840357ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:44:56.19132Z","caller":"traceutil/trace.go:171","msg":"trace[1759557925] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:399; }","duration":"141.149675ms","start":"2026-05-28T21:44:56.05015Z","end":"2026-05-28T21:44:56.191299Z","steps":["trace[1759557925] 'range keys from in-memory index tree' (duration: 140.820567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:56.19133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.274897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-05-28T21:44:56.191528Z","caller":"traceutil/trace.go:171","msg":"trace[1012921036] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:399; }","duration":"785.534274ms","start":"2026-05-28T21:44:55.40598Z","end":"2026-05-28T21:44:56.191515Z","steps":["trace[1012921036] 'agreement among raft nodes before linearized reading' (duration: 785.275098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:56.191568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:55.405952Z","time spent":"785.598145ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5358,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-05-28T21:44:56.411914Z","caller":"traceutil/trace.go:171","msg":"trace[841326646] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:415; }","duration":"220.674688ms","start":"2026-05-28T21:44:56.19122Z","end":"2026-05-28T21:44:56.411895Z","steps":["trace[841326646] 'read index received' (duration: 214.099116ms)","trace[841326646] 'applied index is now lower than readState.Index' (duration: 6.574762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:44:56.412028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001180018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:44:56.412065Z","caller":"traceutil/trace.go:171","msg":"trace[869477425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"1.00123658s","start":"2026-05-28T21:44:55.410807Z","end":"2026-05-28T21:44:56.412043Z","steps":["trace[869477425] 'agreement among raft nodes before linearized reading' (duration: 1.001161068s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:56.412095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:55.4108Z","time spent":"1.001289651s","remote":"127.0.0.1:38264","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-05-28T21:44:56.412183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.666643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:44:56.412348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.971447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:44:56.412322Z","caller":"traceutil/trace.go:171","msg":"trace[292961964] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"1.0061787s","start":"2026-05-28T21:44:55.406117Z","end":"2026-05-28T21:44:56.412295Z","steps":["trace[292961964] 'process raft request' (duration: 999.120427ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:44:56.412416Z","caller":"traceutil/trace.go:171","msg":"trace[1664548566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"659.09237ms","start":"2026-05-28T21:44:55.753316Z","end":"2026-05-28T21:44:56.412409Z","steps":["trace[1664548566] 'agreement among raft nodes before linearized reading' (duration: 659.000848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:56.412439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:55.753288Z","time spent":"659.143442ms","remote":"127.0.0.1:38522","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-05-28T21:44:56.412737Z","caller":"traceutil/trace.go:171","msg":"trace[1767609441] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"1.00489142s","start":"2026-05-28T21:44:55.407042Z","end":"2026-05-28T21:44:56.411934Z","steps":["trace[1767609441] 'process raft request' (duration: 1.004814918s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:56.412926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:55.407022Z","time spent":"1.005824052s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:44:56.413839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:55.40609Z","time spent":"1.006355255s","remote":"127.0.0.1:38522","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-05-28T21:44:56.414013Z","caller":"traceutil/trace.go:171","msg":"trace[1924605594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"650.844328ms","start":"2026-05-28T21:44:55.761427Z","end":"2026-05-28T21:44:56.412271Z","steps":["trace[1924605594] 'agreement among raft nodes before linearized reading' (duration: 650.52631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:44:56.414244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:44:55.761402Z","time spent":"652.751184ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T21:44:56.417892Z","caller":"traceutil/trace.go:171","msg":"trace[1864204463] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"225.304131ms","start":"2026-05-28T21:44:56.192567Z","end":"2026-05-28T21:44:56.417871Z","steps":["trace[1864204463] 'process raft request' (duration: 224.85468ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:44:56.418196Z","caller":"traceutil/trace.go:171","msg":"trace[265234909] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"218.071664ms","start":"2026-05-28T21:44:56.200114Z","end":"2026-05-28T21:44:56.418186Z","steps":["trace[265234909] 'process raft request' (duration: 217.421248ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:06.62253Z","caller":"traceutil/trace.go:171","msg":"trace[1019203793] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"160.48795ms","start":"2026-05-28T21:45:06.462016Z","end":"2026-05-28T21:45:06.622504Z","steps":["trace[1019203793] 'process raft request' (duration: 160.347767ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:06.657129Z","caller":"traceutil/trace.go:171","msg":"trace[1598105710] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:478; }","duration":"193.218845ms","start":"2026-05-28T21:45:06.463883Z","end":"2026-05-28T21:45:06.657102Z","steps":["trace[1598105710] 'read index received' (duration: 158.566323ms)","trace[1598105710] 'applied index is now lower than readState.Index' (duration: 34.651492ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:06.657173Z","caller":"traceutil/trace.go:171","msg":"trace[883559393] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"195.071311ms","start":"2026-05-28T21:45:06.462078Z","end":"2026-05-28T21:45:06.657149Z","steps":["trace[883559393] 'process raft request' (duration: 194.840575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:06.657341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.44164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-28T21:45:06.657354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.321723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T21:45:06.657394Z","caller":"traceutil/trace.go:171","msg":"trace[586039243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:460; }","duration":"192.394335ms","start":"2026-05-28T21:45:06.464993Z","end":"2026-05-28T21:45:06.657387Z","steps":["trace[586039243] 'agreement among raft nodes before linearized reading' (duration: 192.300553ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:06.657418Z","caller":"traceutil/trace.go:171","msg":"trace[306631858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:460; }","duration":"193.555683ms","start":"2026-05-28T21:45:06.463822Z","end":"2026-05-28T21:45:06.657378Z","steps":["trace[306631858] 'agreement among raft nodes before linearized reading' (duration: 193.44324ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:06.827094Z","caller":"traceutil/trace.go:171","msg":"trace[520547077] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"166.594079ms","start":"2026-05-28T21:45:06.660476Z","end":"2026-05-28T21:45:06.82707Z","steps":["trace[520547077] 'process raft request' (duration: 166.540657ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:06.827127Z","caller":"traceutil/trace.go:171","msg":"trace[970034375] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"166.748172ms","start":"2026-05-28T21:45:06.66034Z","end":"2026-05-28T21:45:06.827088Z","steps":["trace[970034375] 'process raft request' (duration: 102.303096ms)","trace[970034375] 'compare' (duration: 64.234531ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:17.222319Z","caller":"traceutil/trace.go:171","msg":"trace[785788481] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"158.849799ms","start":"2026-05-28T21:45:17.063444Z","end":"2026-05-28T21:45:17.222294Z","steps":["trace[785788481] 'process raft request' (duration: 112.071363ms)","trace[785788481] 'compare' (duration: 46.583241ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:17.22242Z","caller":"traceutil/trace.go:171","msg":"trace[1286740077] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"121.846041ms","start":"2026-05-28T21:45:17.10055Z","end":"2026-05-28T21:45:17.222396Z","steps":["trace[1286740077] 'process raft request' (duration: 121.780949ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:17.222449Z","caller":"traceutil/trace.go:171","msg":"trace[3439937] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"154.653478ms","start":"2026-05-28T21:45:17.067757Z","end":"2026-05-28T21:45:17.22241Z","steps":["trace[3439937] 'process raft request' (duration: 154.523705ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:17.222516Z","caller":"traceutil/trace.go:171","msg":"trace[1365618005] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"156.837651ms","start":"2026-05-28T21:45:17.06552Z","end":"2026-05-28T21:45:17.222358Z","steps":["trace[1365618005] 'process raft request' (duration: 156.705197ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:17.222696Z","caller":"traceutil/trace.go:171","msg":"trace[1283535492] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:532; }","duration":"157.067177ms","start":"2026-05-28T21:45:17.065617Z","end":"2026-05-28T21:45:17.222684Z","steps":["trace[1283535492] 'read index received' (duration: 31.67191ms)","trace[1283535492] 'applied index is now lower than readState.Index' (duration: 125.394107ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:17.222903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.273432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14401"} {"level":"info","ts":"2026-05-28T21:45:17.222942Z","caller":"traceutil/trace.go:171","msg":"trace[421143044] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:515; }","duration":"157.353834ms","start":"2026-05-28T21:45:17.065578Z","end":"2026-05-28T21:45:17.222932Z","steps":["trace[421143044] 'agreement among raft nodes before linearized reading' (duration: 157.18375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:17.223357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.110487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-05-28T21:45:17.223414Z","caller":"traceutil/trace.go:171","msg":"trace[781825989] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:515; }","duration":"138.217688ms","start":"2026-05-28T21:45:17.085179Z","end":"2026-05-28T21:45:17.223397Z","steps":["trace[781825989] 'agreement among raft nodes before linearized reading' (duration: 137.737077ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:17.425792Z","caller":"traceutil/trace.go:171","msg":"trace[1411545794] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"164.361625ms","start":"2026-05-28T21:45:17.261407Z","end":"2026-05-28T21:45:17.425769Z","steps":["trace[1411545794] 'read index received' (duration: 26.633788ms)","trace[1411545794] 'applied index is now lower than readState.Index' (duration: 137.726967ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:17.425911Z","caller":"traceutil/trace.go:171","msg":"trace[1198596076] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"177.342109ms","start":"2026-05-28T21:45:17.248557Z","end":"2026-05-28T21:45:17.425899Z","steps":["trace[1198596076] 'process raft request' (duration: 131.200448ms)","trace[1198596076] 'compare' (duration: 45.822603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:17.426022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.59558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4354"} {"level":"info","ts":"2026-05-28T21:45:17.426085Z","caller":"traceutil/trace.go:171","msg":"trace[1245444271] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:518; }","duration":"164.692042ms","start":"2026-05-28T21:45:17.261377Z","end":"2026-05-28T21:45:17.426069Z","steps":["trace[1245444271] 'agreement among raft nodes before linearized reading' (duration: 164.522628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:17.426202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.688855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4671"} {"level":"info","ts":"2026-05-28T21:45:17.426241Z","caller":"traceutil/trace.go:171","msg":"trace[2141263999] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"163.287488ms","start":"2026-05-28T21:45:17.262942Z","end":"2026-05-28T21:45:17.42623Z","steps":["trace[2141263999] 'process raft request' (duration: 162.757865ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:17.426263Z","caller":"traceutil/trace.go:171","msg":"trace[1762529697] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:518; }","duration":"130.828429ms","start":"2026-05-28T21:45:17.29542Z","end":"2026-05-28T21:45:17.426248Z","steps":["trace[1762529697] 'agreement among raft nodes before linearized reading' (duration: 130.641905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.214058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483479182,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T21:45:19.295306Z","caller":"traceutil/trace.go:171","msg":"trace[441987598] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"590.533939ms","start":"2026-05-28T21:45:18.704733Z","end":"2026-05-28T21:45:19.295267Z","steps":["trace[441987598] 'process raft request' (duration: 590.237012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.29556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.704713Z","time spent":"590.702092ms","remote":"127.0.0.1:38522","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-05-28T21:45:19.420361Z","caller":"traceutil/trace.go:171","msg":"trace[1297377930] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"688.368256ms","start":"2026-05-28T21:45:18.731927Z","end":"2026-05-28T21:45:19.420295Z","steps":["trace[1297377930] 'process raft request' (duration: 688.13245ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:19.420434Z","caller":"traceutil/trace.go:171","msg":"trace[1567288801] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"686.852989ms","start":"2026-05-28T21:45:18.733488Z","end":"2026-05-28T21:45:19.420341Z","steps":["trace[1567288801] 'process raft request' (duration: 686.714836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.420657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.731886Z","time spent":"688.659123ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:19.420513Z","caller":"traceutil/trace.go:171","msg":"trace[300654654] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"686.722566ms","start":"2026-05-28T21:45:18.733686Z","end":"2026-05-28T21:45:19.420408Z","steps":["trace[300654654] 'process raft request' (duration: 686.556432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.420874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.733468Z","time spent":"687.176567ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:19.421011Z","caller":"traceutil/trace.go:171","msg":"trace[1786033987] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"676.048086ms","start":"2026-05-28T21:45:18.744877Z","end":"2026-05-28T21:45:19.420925Z","steps":["trace[1786033987] 'process raft request' (duration: 675.404021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.421141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.744863Z","time spent":"676.21274ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:19.420884Z","caller":"traceutil/trace.go:171","msg":"trace[1305414424] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"675.938784ms","start":"2026-05-28T21:45:18.744925Z","end":"2026-05-28T21:45:19.420864Z","steps":["trace[1305414424] 'process raft request' (duration: 675.403141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.421395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.744921Z","time spent":"676.438145ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:19.42145Z","caller":"traceutil/trace.go:171","msg":"trace[1779097474] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"668.027161ms","start":"2026-05-28T21:45:18.753414Z","end":"2026-05-28T21:45:19.421441Z","steps":["trace[1779097474] 'process raft request' (duration: 666.983156ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:19.421481Z","caller":"traceutil/trace.go:171","msg":"trace[208617109] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:543; }","duration":"708.417263ms","start":"2026-05-28T21:45:18.713036Z","end":"2026-05-28T21:45:19.421454Z","steps":["trace[208617109] 'read index received' (duration: 581.885878ms)","trace[208617109] 'applied index is now lower than readState.Index' (duration: 126.530165ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:19.421586Z","caller":"traceutil/trace.go:171","msg":"trace[995982993] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"660.503969ms","start":"2026-05-28T21:45:18.761073Z","end":"2026-05-28T21:45:19.421577Z","steps":["trace[995982993] 'process raft request' (duration: 659.898914ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:19.421574Z","caller":"traceutil/trace.go:171","msg":"trace[1997332896] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"665.686624ms","start":"2026-05-28T21:45:18.755869Z","end":"2026-05-28T21:45:19.421555Z","steps":["trace[1997332896] 'process raft request' (duration: 664.567957ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:19.421634Z","caller":"traceutil/trace.go:171","msg":"trace[1786351749] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"645.107575ms","start":"2026-05-28T21:45:18.776513Z","end":"2026-05-28T21:45:19.42162Z","steps":["trace[1786351749] 'process raft request' (duration: 644.648044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.421634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.761054Z","time spent":"660.56003ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:19.421678Z","caller":"traceutil/trace.go:171","msg":"trace[206022808] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"659.432053ms","start":"2026-05-28T21:45:18.762193Z","end":"2026-05-28T21:45:19.421625Z","steps":["trace[206022808] 'process raft request' (duration: 658.863179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.421753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.776491Z","time spent":"645.176867ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:45:19.421882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.833044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:45:19.421932Z","caller":"traceutil/trace.go:171","msg":"trace[1956254740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"708.908325ms","start":"2026-05-28T21:45:18.713012Z","end":"2026-05-28T21:45:19.42192Z","steps":["trace[1956254740] 'agreement among raft nodes before linearized reading' (duration: 708.751841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.421887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.76218Z","time spent":"659.619218ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:45:19.42197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.713002Z","time spent":"708.961466ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:45:19.422036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.061264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:19.422109Z","caller":"traceutil/trace.go:171","msg":"trace[1361410446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"224.178227ms","start":"2026-05-28T21:45:19.197919Z","end":"2026-05-28T21:45:19.422097Z","steps":["trace[1361410446] 'agreement among raft nodes before linearized reading' (duration: 224.076435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.421193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.733678Z","time spent":"687.292269ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:45:19.42223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.820602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:45:19.421523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.753382Z","time spent":"668.108244ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:45:19.421718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.755724Z","time spent":"665.92827ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:19.421405Z","caller":"traceutil/trace.go:171","msg":"trace[1500213078] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"665.241574ms","start":"2026-05-28T21:45:18.756149Z","end":"2026-05-28T21:45:19.421391Z","steps":["trace[1500213078] 'process raft request' (duration: 664.362733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.422508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.75612Z","time spent":"666.355521ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:45:19.422489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.419328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:45:19.422309Z","caller":"traceutil/trace.go:171","msg":"trace[1073015669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"482.944795ms","start":"2026-05-28T21:45:18.93935Z","end":"2026-05-28T21:45:19.422294Z","steps":["trace[1073015669] 'agreement among raft nodes before linearized reading' (duration: 482.482994ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:19.4226Z","caller":"traceutil/trace.go:171","msg":"trace[422489431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"409.567342ms","start":"2026-05-28T21:45:19.01302Z","end":"2026-05-28T21:45:19.422587Z","steps":["trace[422489431] 'agreement among raft nodes before linearized reading' (duration: 409.339066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.422636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.93932Z","time spent":"483.288613ms","remote":"127.0.0.1:38522","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-05-28T21:45:19.422673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:19.013005Z","time spent":"409.655793ms","remote":"127.0.0.1:38522","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-05-28T21:45:19.421738Z","caller":"traceutil/trace.go:171","msg":"trace[1310451921] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"517.575996ms","start":"2026-05-28T21:45:18.904152Z","end":"2026-05-28T21:45:19.421728Z","steps":["trace[1310451921] 'process raft request' (duration: 517.190806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:19.422749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:18.904118Z","time spent":"518.595871ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:45:19.764576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.271368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:45:19.764806Z","caller":"traceutil/trace.go:171","msg":"trace[1337653577] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"258.851619ms","start":"2026-05-28T21:45:19.50592Z","end":"2026-05-28T21:45:19.764772Z","steps":["trace[1337653577] 'process raft request' (duration: 139.221182ms)","trace[1337653577] 'compare' (duration: 119.079964ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:19.764853Z","caller":"traceutil/trace.go:171","msg":"trace[1808244867] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"244.841859ms","start":"2026-05-28T21:45:19.519993Z","end":"2026-05-28T21:45:19.764835Z","steps":["trace[1808244867] 'process raft request' (duration: 244.737846ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:19.822777Z","caller":"traceutil/trace.go:171","msg":"trace[318820974] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"245.902285ms","start":"2026-05-28T21:45:19.576846Z","end":"2026-05-28T21:45:19.822749Z","steps":["trace[318820974] 'process raft request' (duration: 245.592508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:25.570439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:25.145663Z","time spent":"424.77094ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-28T21:45:25.570626Z","caller":"traceutil/trace.go:171","msg":"trace[858427399] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:638; }","duration":"247.724149ms","start":"2026-05-28T21:45:25.322877Z","end":"2026-05-28T21:45:25.570601Z","steps":["trace[858427399] 'read index received' (duration: 247.627997ms)","trace[858427399] 'applied index is now lower than readState.Index' (duration: 94.742µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:25.570981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.075629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:45:25.571066Z","caller":"traceutil/trace.go:171","msg":"trace[1971146310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"248.175071ms","start":"2026-05-28T21:45:25.32287Z","end":"2026-05-28T21:45:25.571046Z","steps":["trace[1971146310] 'agreement among raft nodes before linearized reading' (duration: 247.856902ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:25.807359Z","caller":"traceutil/trace.go:171","msg":"trace[431385089] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"236.59804ms","start":"2026-05-28T21:45:25.570734Z","end":"2026-05-28T21:45:25.807332Z","steps":["trace[431385089] 'read index received' (duration: 235.600376ms)","trace[431385089] 'applied index is now lower than readState.Index' (duration: 996.694µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:25.807401Z","caller":"traceutil/trace.go:171","msg":"trace[88644520] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"425.636622ms","start":"2026-05-28T21:45:25.381737Z","end":"2026-05-28T21:45:25.807374Z","steps":["trace[88644520] 'process raft request' (duration: 424.752071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:25.807686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.518724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:45:25.807747Z","caller":"traceutil/trace.go:171","msg":"trace[290253370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"340.631557ms","start":"2026-05-28T21:45:25.467099Z","end":"2026-05-28T21:45:25.807731Z","steps":["trace[290253370] 'agreement among raft nodes before linearized reading' (duration: 340.34582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:25.807754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.848471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-28T21:45:25.807795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:25.46708Z","time spent":"340.703769ms","remote":"127.0.0.1:38522","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-05-28T21:45:25.807831Z","caller":"traceutil/trace.go:171","msg":"trace[255793215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"333.956235ms","start":"2026-05-28T21:45:25.473854Z","end":"2026-05-28T21:45:25.80781Z","steps":["trace[255793215] 'agreement among raft nodes before linearized reading' (duration: 333.529964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:25.807883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:25.473844Z","time spent":"334.030396ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:45:25.807891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.904379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T21:45:25.807797Z","caller":"traceutil/trace.go:171","msg":"trace[606097978] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"236.138848ms","start":"2026-05-28T21:45:25.57164Z","end":"2026-05-28T21:45:25.807779Z","steps":["trace[606097978] 'process raft request' (duration: 235.934253ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:25.808132Z","caller":"traceutil/trace.go:171","msg":"trace[380495003] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"233.650407ms","start":"2026-05-28T21:45:25.574467Z","end":"2026-05-28T21:45:25.808118Z","steps":["trace[380495003] 'process raft request' (duration: 233.280448ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:25.80793Z","caller":"traceutil/trace.go:171","msg":"trace[1738921198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"353.974361ms","start":"2026-05-28T21:45:25.453943Z","end":"2026-05-28T21:45:25.807917Z","steps":["trace[1738921198] 'agreement among raft nodes before linearized reading' (duration: 353.673724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:25.808234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:25.453928Z","time spent":"354.296088ms","remote":"127.0.0.1:38522","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-05-28T21:45:25.808366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:25.381703Z","time spent":"425.97944ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:45:26.856561Z","caller":"traceutil/trace.go:171","msg":"trace[1851581024] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"563.160704ms","start":"2026-05-28T21:45:26.293375Z","end":"2026-05-28T21:45:26.856535Z","steps":["trace[1851581024] 'process raft request' (duration: 562.468937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:26.856742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:26.29335Z","time spent":"563.284217ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:45:27.206919Z","caller":"traceutil/trace.go:171","msg":"trace[224291329] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"291.258767ms","start":"2026-05-28T21:45:26.91563Z","end":"2026-05-28T21:45:27.206889Z","steps":["trace[224291329] 'process raft request' (duration: 291.109822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:28.315949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483479416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:45:28.816942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483479416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:45:29.064537Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.463308227s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T21:45:29.067135Z","caller":"traceutil/trace.go:171","msg":"trace[1602295120] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"1.252165245s","start":"2026-05-28T21:45:27.814943Z","end":"2026-05-28T21:45:29.067108Z","steps":["trace[1602295120] 'read index received' (duration: 1.250002072s)","trace[1602295120] 'applied index is now lower than readState.Index' (duration: 2.162163ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:29.067176Z","caller":"traceutil/trace.go:171","msg":"trace[1931138614] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.467785255s","start":"2026-05-28T21:45:27.59935Z","end":"2026-05-28T21:45:29.067135Z","steps":["trace[1931138614] 'process raft request' (duration: 1.465887698s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:29.067298Z","caller":"traceutil/trace.go:171","msg":"trace[1415120838] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.017913114s","start":"2026-05-28T21:45:28.049373Z","end":"2026-05-28T21:45:29.067286Z","steps":["trace[1415120838] 'process raft request' (duration: 1.017682459s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:29.067409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:28.049348Z","time spent":"1.017984416s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:45:29.067466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:27.59932Z","time spent":"1.46798106s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:45:29.067588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246805965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:45:29.06764Z","caller":"traceutil/trace.go:171","msg":"trace[1750733369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"1.246873646s","start":"2026-05-28T21:45:27.820748Z","end":"2026-05-28T21:45:29.067621Z","steps":["trace[1750733369] 'agreement among raft nodes before linearized reading' (duration: 1.246701342s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:29.06768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:27.820743Z","time spent":"1.246926877s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T21:45:29.067806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.685096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:29.067864Z","caller":"traceutil/trace.go:171","msg":"trace[913171809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"870.766279ms","start":"2026-05-28T21:45:28.197084Z","end":"2026-05-28T21:45:29.06785Z","steps":["trace[913171809] 'agreement among raft nodes before linearized reading' (duration: 870.691467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:29.067906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:28.197071Z","time spent":"870.82853ms","remote":"127.0.0.1:38264","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-05-28T21:45:29.067899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246335594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:45:29.067951Z","caller":"traceutil/trace.go:171","msg":"trace[659114505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.246391935s","start":"2026-05-28T21:45:27.821541Z","end":"2026-05-28T21:45:29.067933Z","steps":["trace[659114505] 'agreement among raft nodes before linearized reading' (duration: 1.246237421s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:29.06798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246479277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:45:29.067998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:27.821537Z","time spent":"1.246449176s","remote":"127.0.0.1:38522","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-05-28T21:45:29.068019Z","caller":"traceutil/trace.go:171","msg":"trace[1010592120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"1.24657526s","start":"2026-05-28T21:45:27.821433Z","end":"2026-05-28T21:45:29.068008Z","steps":["trace[1010592120] 'agreement among raft nodes before linearized reading' (duration: 1.246401415s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:29.068049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:27.821413Z","time spent":"1.24662948s","remote":"127.0.0.1:38522","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-05-28T21:45:29.06868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253731843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:45:29.068766Z","caller":"traceutil/trace.go:171","msg":"trace[192447931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.253828615s","start":"2026-05-28T21:45:27.814907Z","end":"2026-05-28T21:45:29.068735Z","steps":["trace[192447931] 'agreement among raft nodes before linearized reading' (duration: 1.253155739s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:29.068802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:27.814891Z","time spent":"1.253901077s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T21:45:30.676074Z","caller":"traceutil/trace.go:171","msg":"trace[160086533] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"367.595682ms","start":"2026-05-28T21:45:30.308452Z","end":"2026-05-28T21:45:30.676047Z","steps":["trace[160086533] 'read index received' (duration: 365.909891ms)","trace[160086533] 'applied index is now lower than readState.Index' (duration: 1.683991ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:30.676308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.844238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-28T21:45:30.67635Z","caller":"traceutil/trace.go:171","msg":"trace[682484978] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:647; }","duration":"367.9419ms","start":"2026-05-28T21:45:30.308397Z","end":"2026-05-28T21:45:30.676339Z","steps":["trace[682484978] 'agreement among raft nodes before linearized reading' (duration: 367.759256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:30.67639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:30.308374Z","time spent":"368.007762ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-28T21:45:30.687724Z","caller":"traceutil/trace.go:171","msg":"trace[278454342] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"150.533608ms","start":"2026-05-28T21:45:30.537153Z","end":"2026-05-28T21:45:30.687686Z","steps":["trace[278454342] 'process raft request' (duration: 149.774469ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:34.984078Z","caller":"traceutil/trace.go:171","msg":"trace[990235311] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"117.939065ms","start":"2026-05-28T21:45:34.866112Z","end":"2026-05-28T21:45:34.984051Z","steps":["trace[990235311] 'process raft request' (duration: 93.976513ms)","trace[990235311] 'compare' (duration: 23.778258ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:35.167604Z","caller":"traceutil/trace.go:171","msg":"trace[242144041] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"127.027626ms","start":"2026-05-28T21:45:35.040539Z","end":"2026-05-28T21:45:35.167567Z","steps":["trace[242144041] 'process raft request' (duration: 125.900969ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:35.301761Z","caller":"traceutil/trace.go:171","msg":"trace[258424885] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"123.684895ms","start":"2026-05-28T21:45:35.178042Z","end":"2026-05-28T21:45:35.301727Z","steps":["trace[258424885] 'process raft request' (duration: 109.366947ms)","trace[258424885] 'compare' (duration: 13.98455ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:35.301793Z","caller":"traceutil/trace.go:171","msg":"trace[578442774] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"123.536001ms","start":"2026-05-28T21:45:35.178233Z","end":"2026-05-28T21:45:35.301769Z","steps":["trace[578442774] 'process raft request' (duration: 123.425619ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:35.302113Z","caller":"traceutil/trace.go:171","msg":"trace[850242085] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"123.813948ms","start":"2026-05-28T21:45:35.178278Z","end":"2026-05-28T21:45:35.302092Z","steps":["trace[850242085] 'process raft request' (duration: 123.452529ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:35.302497Z","caller":"traceutil/trace.go:171","msg":"trace[6309293] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:758; }","duration":"105.756849ms","start":"2026-05-28T21:45:35.196724Z","end":"2026-05-28T21:45:35.302481Z","steps":["trace[6309293] 'read index received' (duration: 67.194063ms)","trace[6309293] 'applied index is now lower than readState.Index' (duration: 38.562086ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:35.302535Z","caller":"traceutil/trace.go:171","msg":"trace[1569783732] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"123.847199ms","start":"2026-05-28T21:45:35.178674Z","end":"2026-05-28T21:45:35.302521Z","steps":["trace[1569783732] 'process raft request' (duration: 123.324067ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:35.302613Z","caller":"traceutil/trace.go:171","msg":"trace[1408504157] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"123.661565ms","start":"2026-05-28T21:45:35.178914Z","end":"2026-05-28T21:45:35.302575Z","steps":["trace[1408504157] 'process raft request' (duration: 123.46113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:35.302739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.998945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:35.302815Z","caller":"traceutil/trace.go:171","msg":"trace[190895965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"106.133939ms","start":"2026-05-28T21:45:35.196663Z","end":"2026-05-28T21:45:35.302797Z","steps":["trace[190895965] 'agreement among raft nodes before linearized reading' (duration: 105.945613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:35.718603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.012123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:35.718664Z","caller":"traceutil/trace.go:171","msg":"trace[1421038227] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:741; }","duration":"103.109016ms","start":"2026-05-28T21:45:35.615538Z","end":"2026-05-28T21:45:35.718647Z","steps":["trace[1421038227] 'agreement among raft nodes before linearized reading' (duration: 102.980273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:35.718683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.591353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:35.718748Z","caller":"traceutil/trace.go:171","msg":"trace[1479808078] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:741; }","duration":"102.679315ms","start":"2026-05-28T21:45:35.616053Z","end":"2026-05-28T21:45:35.718732Z","steps":["trace[1479808078] 'agreement among raft nodes before linearized reading' (duration: 102.567192ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:40.946064Z","caller":"traceutil/trace.go:171","msg":"trace[131344096] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"136.903667ms","start":"2026-05-28T21:45:40.809142Z","end":"2026-05-28T21:45:40.946046Z","steps":["trace[131344096] 'read index received' (duration: 134.861487ms)","trace[131344096] 'applied index is now lower than readState.Index' (duration: 2.04123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:40.946218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.06591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:45:40.946261Z","caller":"traceutil/trace.go:171","msg":"trace[1196629448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"137.162742ms","start":"2026-05-28T21:45:40.809088Z","end":"2026-05-28T21:45:40.946251Z","steps":["trace[1196629448] 'agreement among raft nodes before linearized reading' (duration: 137.029689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:44.118623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.901042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-28T21:45:44.118772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.605658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:45:44.11879Z","caller":"traceutil/trace.go:171","msg":"trace[229365813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"771.111917ms","start":"2026-05-28T21:45:43.347656Z","end":"2026-05-28T21:45:44.118768Z","steps":["trace[229365813] 'range keys from in-memory index tree' (duration: 770.687376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:44.118889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:43.347611Z","time spent":"771.264642ms","remote":"127.0.0.1:38522","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-05-28T21:45:44.118682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.993787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:45:44.118987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.369112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:45:44.119017Z","caller":"traceutil/trace.go:171","msg":"trace[1944309313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"753.323305ms","start":"2026-05-28T21:45:43.365651Z","end":"2026-05-28T21:45:44.118974Z","steps":["trace[1944309313] 'range keys from in-memory index tree' (duration: 752.889913ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:44.119044Z","caller":"traceutil/trace.go:171","msg":"trace[1498853928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"752.480684ms","start":"2026-05-28T21:45:43.366545Z","end":"2026-05-28T21:45:44.119026Z","steps":["trace[1498853928] 'range keys from in-memory index tree' (duration: 752.260129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:44.118693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.489244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9xd87\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-28T21:45:44.119094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:43.366525Z","time spent":"752.561037ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:45:44.119113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:43.365641Z","time spent":"753.445898ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T21:45:44.119127Z","caller":"traceutil/trace.go:171","msg":"trace[729687959] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9xd87; range_end:; response_count:1; response_revision:793; }","duration":"938.968086ms","start":"2026-05-28T21:45:43.180134Z","end":"2026-05-28T21:45:44.119102Z","steps":["trace[729687959] 'range keys from in-memory index tree' (duration: 938.329929ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:44.118817Z","caller":"traceutil/trace.go:171","msg":"trace[488974155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"752.662269ms","start":"2026-05-28T21:45:43.36614Z","end":"2026-05-28T21:45:44.118803Z","steps":["trace[488974155] 'range keys from in-memory index tree' (duration: 752.517656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:44.11919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:43.180117Z","time spent":"939.056059ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9xd87\" "} {"level":"warn","ts":"2026-05-28T21:45:44.119232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:43.36613Z","time spent":"753.081099ms","remote":"127.0.0.1:38522","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-05-28T21:45:44.471559Z","caller":"traceutil/trace.go:171","msg":"trace[354117423] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"347.254646ms","start":"2026-05-28T21:45:44.124275Z","end":"2026-05-28T21:45:44.471529Z","steps":["trace[354117423] 'process raft request' (duration: 347.008641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:44.471758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:44.124255Z","time spent":"347.374691ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:45:44.480357Z","caller":"traceutil/trace.go:171","msg":"trace[724220166] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:824; }","duration":"283.255804ms","start":"2026-05-28T21:45:44.19709Z","end":"2026-05-28T21:45:44.480346Z","steps":["trace[724220166] 'read index received' (duration: 274.400477ms)","trace[724220166] 'applied index is now lower than readState.Index' (duration: 8.854447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:44.48044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.360036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:44.480477Z","caller":"traceutil/trace.go:171","msg":"trace[1366977786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"283.433487ms","start":"2026-05-28T21:45:44.19702Z","end":"2026-05-28T21:45:44.480454Z","steps":["trace[1366977786] 'agreement among raft nodes before linearized reading' (duration: 283.365005ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:44.48058Z","caller":"traceutil/trace.go:171","msg":"trace[107455622] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"356.158924ms","start":"2026-05-28T21:45:44.124405Z","end":"2026-05-28T21:45:44.480564Z","steps":["trace[107455622] 'process raft request' (duration: 355.872337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:44.480644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:44.124391Z","time spent":"356.219076ms","remote":"127.0.0.1:38522","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-05-28T21:45:44.480684Z","caller":"traceutil/trace.go:171","msg":"trace[1216732281] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"353.327425ms","start":"2026-05-28T21:45:44.127326Z","end":"2026-05-28T21:45:44.480654Z","steps":["trace[1216732281] 'process raft request' (duration: 352.982807ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:44.480761Z","caller":"traceutil/trace.go:171","msg":"trace[982836799] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"356.42785ms","start":"2026-05-28T21:45:44.124325Z","end":"2026-05-28T21:45:44.480753Z","steps":["trace[982836799] 'process raft request' (duration: 355.931758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:44.480806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:44.124303Z","time spent":"356.478212ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:45:44.480831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:44.127306Z","time spent":"353.444558ms","remote":"127.0.0.1:38456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:45:44.480944Z","caller":"traceutil/trace.go:171","msg":"trace[523195215] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"356.575925ms","start":"2026-05-28T21:45:44.124312Z","end":"2026-05-28T21:45:44.480888Z","steps":["trace[523195215] 'process raft request' (duration: 355.885198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:44.481192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:44.124296Z","time spent":"356.767979ms","remote":"127.0.0.1:38522","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-05-28T21:45:46.717085Z","caller":"traceutil/trace.go:171","msg":"trace[760909890] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:837; }","duration":"180.724611ms","start":"2026-05-28T21:45:46.536336Z","end":"2026-05-28T21:45:46.717061Z","steps":["trace[760909890] 'read index received' (duration: 180.162657ms)","trace[760909890] 'applied index is now lower than readState.Index' (duration: 560.884µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:46.717225Z","caller":"traceutil/trace.go:171","msg":"trace[251079693] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"204.364926ms","start":"2026-05-28T21:45:46.512848Z","end":"2026-05-28T21:45:46.717213Z","steps":["trace[251079693] 'process raft request' (duration: 203.772442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:46.717439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.071509ms","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-05-28T21:45:46.717519Z","caller":"traceutil/trace.go:171","msg":"trace[1211103621] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:807; }","duration":"181.185272ms","start":"2026-05-28T21:45:46.536313Z","end":"2026-05-28T21:45:46.717499Z","steps":["trace[1211103621] 'agreement among raft nodes before linearized reading' (duration: 181.035819ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:46.717565Z","caller":"traceutil/trace.go:171","msg":"trace[1111256733] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"204.359136ms","start":"2026-05-28T21:45:46.513176Z","end":"2026-05-28T21:45:46.717535Z","steps":["trace[1111256733] 'process raft request' (duration: 203.798583ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:48.653916Z","caller":"traceutil/trace.go:171","msg":"trace[2048091562] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"140.74966ms","start":"2026-05-28T21:45:48.513148Z","end":"2026-05-28T21:45:48.653897Z","steps":["trace[2048091562] 'process raft request' (duration: 140.053943ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:48.726468Z","caller":"traceutil/trace.go:171","msg":"trace[756069057] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:872; }","duration":"205.143274ms","start":"2026-05-28T21:45:48.521296Z","end":"2026-05-28T21:45:48.72644Z","steps":["trace[756069057] 'read index received' (duration: 131.70938ms)","trace[756069057] 'applied index is now lower than readState.Index' (duration: 73.432644ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:48.726629Z","caller":"traceutil/trace.go:171","msg":"trace[35284642] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"210.995367ms","start":"2026-05-28T21:45:48.515607Z","end":"2026-05-28T21:45:48.726602Z","steps":["trace[35284642] 'process raft request' (duration: 192.848796ms)","trace[35284642] 'compare' (duration: 17.814213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:48.72688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.561715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:45:48.726951Z","caller":"traceutil/trace.go:171","msg":"trace[2023099851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:841; }","duration":"205.669398ms","start":"2026-05-28T21:45:48.521264Z","end":"2026-05-28T21:45:48.726933Z","steps":["trace[2023099851] 'agreement among raft nodes before linearized reading' (duration: 205.39844ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:58.670631Z","caller":"traceutil/trace.go:171","msg":"trace[1298329987] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"143.461696ms","start":"2026-05-28T21:45:58.527145Z","end":"2026-05-28T21:45:58.670606Z","steps":["trace[1298329987] 'process raft request' (duration: 142.919853ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:58.670762Z","caller":"traceutil/trace.go:171","msg":"trace[1978088203] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"141.515218ms","start":"2026-05-28T21:45:58.529196Z","end":"2026-05-28T21:45:58.670711Z","steps":["trace[1978088203] 'process raft request' (duration: 141.348664ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:09.134781Z","caller":"traceutil/trace.go:171","msg":"trace[944946309] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"187.576622ms","start":"2026-05-28T21:46:08.947181Z","end":"2026-05-28T21:46:09.134757Z","steps":["trace[944946309] 'process raft request' (duration: 158.395573ms)","trace[944946309] 'compare' (duration: 28.973244ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:09.134962Z","caller":"traceutil/trace.go:171","msg":"trace[315950452] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"187.652522ms","start":"2026-05-28T21:46:08.947277Z","end":"2026-05-28T21:46:09.13493Z","steps":["trace[315950452] 'process raft request' (duration: 187.438277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:22.530794Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483480228,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:46:22.631418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473246808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:46:22.631502Z","caller":"traceutil/trace.go:171","msg":"trace[307786032] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1016; }","duration":"1.473370591s","start":"2026-05-28T21:46:21.158112Z","end":"2026-05-28T21:46:22.631482Z","steps":["trace[307786032] 'count revisions from in-memory index tree' (duration: 1.473186047s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:22.631508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434028384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:46:22.631545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:21.158098Z","time spent":"1.473436023s","remote":"127.0.0.1:38508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:46:22.631545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368145461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T21:46:22.631585Z","caller":"traceutil/trace.go:171","msg":"trace[745296542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"1.434143217s","start":"2026-05-28T21:46:21.197423Z","end":"2026-05-28T21:46:22.631566Z","steps":["trace[745296542] 'range keys from in-memory index tree' (duration: 1.433956693s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:22.631607Z","caller":"traceutil/trace.go:171","msg":"trace[1755651495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.368221394s","start":"2026-05-28T21:46:21.263368Z","end":"2026-05-28T21:46:22.631589Z","steps":["trace[1755651495] 'range keys from in-memory index tree' (duration: 1.368024539s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:22.631621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:21.197404Z","time spent":"1.434210219s","remote":"127.0.0.1:38264","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-05-28T21:46:22.631641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:21.263362Z","time spent":"1.368272035s","remote":"127.0.0.1:38522","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-05-28T21:46:22.631694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379387525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:46:22.63183Z","caller":"traceutil/trace.go:171","msg":"trace[1016938893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.379575329s","start":"2026-05-28T21:46:21.252235Z","end":"2026-05-28T21:46:22.63181Z","steps":["trace[1016938893] 'range keys from in-memory index tree' (duration: 1.37920484s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:22.631885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:21.252219Z","time spent":"1.379655622s","remote":"127.0.0.1:38522","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-05-28T21:46:22.631889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263845635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:46:22.631977Z","caller":"traceutil/trace.go:171","msg":"trace[377270065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.263974619s","start":"2026-05-28T21:46:21.367984Z","end":"2026-05-28T21:46:22.631959Z","steps":["trace[377270065] 'range keys from in-memory index tree' (duration: 1.263663892s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:22.632023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:21.367961Z","time spent":"1.264052612s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T21:46:23.031423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483480228,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:46:23.532212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483480228,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:46:23.671683Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.826590141s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T21:46:23.672006Z","caller":"traceutil/trace.go:171","msg":"trace[36837183] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.827014451s","start":"2026-05-28T21:46:21.844968Z","end":"2026-05-28T21:46:23.671982Z","steps":["trace[36837183] 'process raft request' (duration: 1.826865538s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:23.672146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:21.844938Z","time spent":"1.827135344s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:46:23.729234Z","caller":"traceutil/trace.go:171","msg":"trace[1864897488] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.093061242s","start":"2026-05-28T21:46:22.636146Z","end":"2026-05-28T21:46:23.729207Z","steps":["trace[1864897488] 'process raft request' (duration: 1.093007391s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:23.729255Z","caller":"traceutil/trace.go:171","msg":"trace[1243020519] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.094131678s","start":"2026-05-28T21:46:22.635091Z","end":"2026-05-28T21:46:23.729223Z","steps":["trace[1243020519] 'process raft request' (duration: 1.093953053s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:23.729393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:22.636134Z","time spent":"1.093181325s","remote":"127.0.0.1:38522","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-05-28T21:46:23.729399Z","caller":"traceutil/trace.go:171","msg":"trace[1995358070] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1057; }","duration":"1.698844654s","start":"2026-05-28T21:46:22.030524Z","end":"2026-05-28T21:46:23.729369Z","steps":["trace[1995358070] 'read index received' (duration: 1.641374027s)","trace[1995358070] 'applied index is now lower than readState.Index' (duration: 57.468797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:23.729415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:22.635073Z","time spent":"1.094259921s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:46:23.729573Z","caller":"traceutil/trace.go:171","msg":"trace[1835705257] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.091900354s","start":"2026-05-28T21:46:22.637659Z","end":"2026-05-28T21:46:23.72956Z","steps":["trace[1835705257] 'process raft request' (duration: 1.091517825s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:23.72957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699024548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-28T21:46:23.729609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096447884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:46:23.729629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:22.637652Z","time spent":"1.091950185s","remote":"127.0.0.1:38522","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-05-28T21:46:23.729642Z","caller":"traceutil/trace.go:171","msg":"trace[171368201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"1.096528906s","start":"2026-05-28T21:46:22.633103Z","end":"2026-05-28T21:46:23.729632Z","steps":["trace[171368201] 'agreement among raft nodes before linearized reading' (duration: 1.096470115s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:23.729637Z","caller":"traceutil/trace.go:171","msg":"trace[1455057779] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1020; }","duration":"1.699147861s","start":"2026-05-28T21:46:22.030474Z","end":"2026-05-28T21:46:23.729622Z","steps":["trace[1455057779] 'agreement among raft nodes before linearized reading' (duration: 1.699017608s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:23.729678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:22.633082Z","time spent":"1.096587767s","remote":"127.0.0.1:38276","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-05-28T21:46:23.729684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:22.030453Z","time spent":"1.699221513s","remote":"127.0.0.1:38658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:46:23.729811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.962574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:46:23.729839Z","caller":"traceutil/trace.go:171","msg":"trace[1489174441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"624.025465ms","start":"2026-05-28T21:46:23.105805Z","end":"2026-05-28T21:46:23.729831Z","steps":["trace[1489174441] 'agreement among raft nodes before linearized reading' (duration: 623.932253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:23.729862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:23.105783Z","time spent":"624.074266ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T21:46:23.729928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.433578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T21:46:23.729951Z","caller":"traceutil/trace.go:171","msg":"trace[127049143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"664.49208ms","start":"2026-05-28T21:46:23.065452Z","end":"2026-05-28T21:46:23.729944Z","steps":["trace[127049143] 'agreement among raft nodes before linearized reading' (duration: 664.396128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:23.729972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:23.065433Z","time spent":"664.533791ms","remote":"127.0.0.1:38522","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-05-28T21:46:42.060237Z","caller":"traceutil/trace.go:171","msg":"trace[1959483970] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"143.06263ms","start":"2026-05-28T21:46:41.917144Z","end":"2026-05-28T21:46:42.060207Z","steps":["trace[1959483970] 'process raft request' (duration: 142.884095ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:42.156054Z","caller":"traceutil/trace.go:171","msg":"trace[1614154478] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"237.515557ms","start":"2026-05-28T21:46:41.91852Z","end":"2026-05-28T21:46:42.156036Z","steps":["trace[1614154478] 'process raft request' (duration: 237.406834ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:42.156118Z","caller":"traceutil/trace.go:171","msg":"trace[203170390] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"237.443134ms","start":"2026-05-28T21:46:41.918667Z","end":"2026-05-28T21:46:42.15611Z","steps":["trace[203170390] 'process raft request' (duration: 237.334952ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:42.156109Z","caller":"traceutil/trace.go:171","msg":"trace[1189068059] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1122; }","duration":"210.501669ms","start":"2026-05-28T21:46:41.945586Z","end":"2026-05-28T21:46:42.156088Z","steps":["trace[1189068059] 'read index received' (duration: 114.460314ms)","trace[1189068059] 'applied index is now lower than readState.Index' (duration: 96.040095ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:42.156052Z","caller":"traceutil/trace.go:171","msg":"trace[1486751200] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"238.472509ms","start":"2026-05-28T21:46:41.917549Z","end":"2026-05-28T21:46:42.156021Z","steps":["trace[1486751200] 'process raft request' (duration: 227.40038ms)","trace[1486751200] 'compare' (duration: 10.752132ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:42.156351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.594748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-28T21:46:42.156379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.782196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:46:42.156425Z","caller":"traceutil/trace.go:171","msg":"trace[614028758] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1081; }","duration":"200.699571ms","start":"2026-05-28T21:46:41.955703Z","end":"2026-05-28T21:46:42.156402Z","steps":["trace[614028758] 'agreement among raft nodes before linearized reading' (duration: 200.559488ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:42.15643Z","caller":"traceutil/trace.go:171","msg":"trace[295709164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"210.846708ms","start":"2026-05-28T21:46:41.945567Z","end":"2026-05-28T21:46:42.156414Z","steps":["trace[295709164] 'agreement among raft nodes before linearized reading' (duration: 210.666293ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:46.277821Z","caller":"traceutil/trace.go:171","msg":"trace[732976386] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"179.597967ms","start":"2026-05-28T21:46:46.098206Z","end":"2026-05-28T21:46:46.277804Z","steps":["trace[732976386] 'read index received' (duration: 179.495475ms)","trace[732976386] 'applied index is now lower than readState.Index' (duration: 101.692µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:46.278596Z","caller":"traceutil/trace.go:171","msg":"trace[1338588787] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"448.529179ms","start":"2026-05-28T21:46:45.830019Z","end":"2026-05-28T21:46:46.278548Z","steps":["trace[1338588787] 'process raft request' (duration: 447.662568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:46.278778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:45.829997Z","time spent":"448.679172ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:46:46.27911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.869759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-28T21:46:46.279232Z","caller":"traceutil/trace.go:171","msg":"trace[1611740501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"181.054553ms","start":"2026-05-28T21:46:46.098158Z","end":"2026-05-28T21:46:46.279212Z","steps":["trace[1611740501] 'agreement among raft nodes before linearized reading' (duration: 179.766431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:46.714571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.146529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:46.714695Z","caller":"traceutil/trace.go:171","msg":"trace[1626747299] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"430.448518ms","start":"2026-05-28T21:46:46.284222Z","end":"2026-05-28T21:46:46.714671Z","steps":["trace[1626747299] 'process raft request' (duration: 188.094685ms)","trace[1626747299] 'compare' (duration: 242.004595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:46.714782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:46.284205Z","time spent":"430.53637ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:46:46.805588Z","caller":"traceutil/trace.go:171","msg":"trace[1655147460] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"521.250237ms","start":"2026-05-28T21:46:46.284313Z","end":"2026-05-28T21:46:46.805563Z","steps":["trace[1655147460] 'process raft request' (duration: 521.188345ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:46.805957Z","caller":"traceutil/trace.go:171","msg":"trace[2065076453] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"521.635536ms","start":"2026-05-28T21:46:46.284305Z","end":"2026-05-28T21:46:46.80594Z","steps":["trace[2065076453] 'process raft request' (duration: 521.161014ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:46.805624Z","caller":"traceutil/trace.go:171","msg":"trace[2069832906] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"520.54341ms","start":"2026-05-28T21:46:46.285058Z","end":"2026-05-28T21:46:46.805601Z","steps":["trace[2069832906] 'process raft request' (duration: 520.471248ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:46.805949Z","caller":"traceutil/trace.go:171","msg":"trace[755904939] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"521.699467ms","start":"2026-05-28T21:46:46.284208Z","end":"2026-05-28T21:46:46.805908Z","steps":["trace[755904939] 'process raft request' (duration: 521.099263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:46.806206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:46.285049Z","time spent":"521.083342ms","remote":"127.0.0.1:38522","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-05-28T21:46:46.806328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:46.284293Z","time spent":"521.754658ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:46:46.805885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:46.284293Z","time spent":"521.464141ms","remote":"127.0.0.1:38522","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-05-28T21:46:46.806534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:46.28418Z","time spent":"522.24419ms","remote":"127.0.0.1:38522","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-05-28T21:46:48.850601Z","caller":"traceutil/trace.go:171","msg":"trace[1486960901] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"256.807906ms","start":"2026-05-28T21:46:48.593774Z","end":"2026-05-28T21:46:48.850582Z","steps":["trace[1486960901] 'read index received' (duration: 256.56503ms)","trace[1486960901] 'applied index is now lower than readState.Index' (duration: 241.836µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:48.850874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.367064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T21:46:48.850939Z","caller":"traceutil/trace.go:171","msg":"trace[1900450778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"131.481237ms","start":"2026-05-28T21:46:48.719448Z","end":"2026-05-28T21:46:48.850929Z","steps":["trace[1900450778] 'agreement among raft nodes before linearized reading' (duration: 131.324253ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:48.850686Z","caller":"traceutil/trace.go:171","msg":"trace[780799809] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"566.560669ms","start":"2026-05-28T21:46:48.284092Z","end":"2026-05-28T21:46:48.850653Z","steps":["trace[780799809] 'process raft request' (duration: 566.342464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:48.851093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.00334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:46:48.851171Z","caller":"traceutil/trace.go:171","msg":"trace[2104064369] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1097; }","duration":"257.41121ms","start":"2026-05-28T21:46:48.593741Z","end":"2026-05-28T21:46:48.851152Z","steps":["trace[2104064369] 'agreement among raft nodes before linearized reading' (duration: 256.96279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:48.85138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:48.284066Z","time spent":"567.061001ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:46:50.450929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.560399ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-28T21:46:51.229218Z","caller":"traceutil/trace.go:171","msg":"trace[707399181] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"286.133689ms","start":"2026-05-28T21:46:50.943053Z","end":"2026-05-28T21:46:51.229186Z","steps":["trace[707399181] 'process raft request' (duration: 238.234184ms)","trace[707399181] 'compare' (duration: 47.764541ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:51.229214Z","caller":"traceutil/trace.go:171","msg":"trace[266791628] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"283.737881ms","start":"2026-05-28T21:46:50.945445Z","end":"2026-05-28T21:46:51.229182Z","steps":["trace[266791628] 'read index received' (duration: 235.824566ms)","trace[266791628] 'applied index is now lower than readState.Index' (duration: 47.912155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:51.229815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.354586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:46:51.229823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.011088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:46:51.22988Z","caller":"traceutil/trace.go:171","msg":"trace[895485680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"284.463519ms","start":"2026-05-28T21:46:50.9454Z","end":"2026-05-28T21:46:51.229864Z","steps":["trace[895485680] 'agreement among raft nodes before linearized reading' (duration: 284.273744ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:51.229886Z","caller":"traceutil/trace.go:171","msg":"trace[584766942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"282.105281ms","start":"2026-05-28T21:46:50.947766Z","end":"2026-05-28T21:46:51.229871Z","steps":["trace[584766942] 'agreement among raft nodes before linearized reading' (duration: 281.926267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:51.229807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.938871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:46:51.230089Z","caller":"traceutil/trace.go:171","msg":"trace[696045612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"283.247928ms","start":"2026-05-28T21:46:50.946831Z","end":"2026-05-28T21:46:51.230079Z","steps":["trace[696045612] 'agreement among raft nodes before linearized reading' (duration: 282.769276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:51.230087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.102444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:46:51.230482Z","caller":"traceutil/trace.go:171","msg":"trace[1980769159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"283.532266ms","start":"2026-05-28T21:46:50.946864Z","end":"2026-05-28T21:46:51.230397Z","steps":["trace[1980769159] 'agreement among raft nodes before linearized reading' (duration: 282.851529ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:55.526461Z","caller":"traceutil/trace.go:171","msg":"trace[1508545347] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"116.253508ms","start":"2026-05-28T21:46:55.410182Z","end":"2026-05-28T21:46:55.526436Z","steps":["trace[1508545347] 'process raft request' (duration: 116.076994ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:55.526654Z","caller":"traceutil/trace.go:171","msg":"trace[1171623305] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"116.228727ms","start":"2026-05-28T21:46:55.410391Z","end":"2026-05-28T21:46:55.52662Z","steps":["trace[1171623305] 'process raft request' (duration: 116.010652ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:57.829777Z","caller":"traceutil/trace.go:171","msg":"trace[1145591741] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"258.23374ms","start":"2026-05-28T21:46:57.571521Z","end":"2026-05-28T21:46:57.829755Z","steps":["trace[1145591741] 'process raft request' (duration: 258.102017ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:57.879204Z","caller":"traceutil/trace.go:171","msg":"trace[1053103152] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"130.948715ms","start":"2026-05-28T21:46:57.748235Z","end":"2026-05-28T21:46:57.879183Z","steps":["trace[1053103152] 'process raft request' (duration: 130.844043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:59.730576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.297444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:59.730684Z","caller":"traceutil/trace.go:171","msg":"trace[1681609271] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"161.229082ms","start":"2026-05-28T21:46:59.569437Z","end":"2026-05-28T21:46:59.730666Z","steps":["trace[1681609271] 'read index received' (duration: 44.502µs)","trace[1681609271] 'applied index is now lower than readState.Index' (duration: 161.18343ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:59.730713Z","caller":"traceutil/trace.go:171","msg":"trace[999636896] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"315.291738ms","start":"2026-05-28T21:46:59.415399Z","end":"2026-05-28T21:46:59.73069Z","steps":["trace[999636896] 'process raft request' (duration: 120.799988ms)","trace[999636896] 'compare' (duration: 194.190242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:59.730806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:59.415378Z","time spent":"315.38165ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:46:59.730917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.483977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:46:59.73099Z","caller":"traceutil/trace.go:171","msg":"trace[794043871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1132; }","duration":"161.60094ms","start":"2026-05-28T21:46:59.569378Z","end":"2026-05-28T21:46:59.730979Z","steps":["trace[794043871] 'agreement among raft nodes before linearized reading' (duration: 161.392345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:00.00074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.766366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:47:00.000813Z","caller":"traceutil/trace.go:171","msg":"trace[1250465837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1134; }","duration":"166.890928ms","start":"2026-05-28T21:46:59.833905Z","end":"2026-05-28T21:47:00.000796Z","steps":["trace[1250465837] 'range keys from in-memory index tree' (duration: 166.610333ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:06.453464Z","caller":"traceutil/trace.go:171","msg":"trace[2047448821] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"111.645326ms","start":"2026-05-28T21:47:06.341775Z","end":"2026-05-28T21:47:06.45342Z","steps":["trace[2047448821] 'process raft request' (duration: 111.526443ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:06.7195Z","caller":"traceutil/trace.go:171","msg":"trace[1437049906] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"260.005434ms","start":"2026-05-28T21:47:06.459457Z","end":"2026-05-28T21:47:06.719462Z","steps":["trace[1437049906] 'process raft request' (duration: 233.600122ms)","trace[1437049906] 'compare' (duration: 25.684885ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:06.720266Z","caller":"traceutil/trace.go:171","msg":"trace[1474592763] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"260.718072ms","start":"2026-05-28T21:47:06.459522Z","end":"2026-05-28T21:47:06.72024Z","steps":["trace[1474592763] 'process raft request' (duration: 260.594409ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:06.941149Z","caller":"traceutil/trace.go:171","msg":"trace[367085830] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"204.212745ms","start":"2026-05-28T21:47:06.736909Z","end":"2026-05-28T21:47:06.941122Z","steps":["trace[367085830] 'process raft request' (duration: 192.653064ms)","trace[367085830] 'compare' (duration: 11.326616ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:06.94117Z","caller":"traceutil/trace.go:171","msg":"trace[77757830] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"203.983059ms","start":"2026-05-28T21:47:06.737154Z","end":"2026-05-28T21:47:06.941137Z","steps":["trace[77757830] 'process raft request' (duration: 203.917678ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:07.363893Z","caller":"traceutil/trace.go:171","msg":"trace[1084825422] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"276.460093ms","start":"2026-05-28T21:47:07.087411Z","end":"2026-05-28T21:47:07.363872Z","steps":["trace[1084825422] 'process raft request' (duration: 276.32509ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:07.375192Z","caller":"traceutil/trace.go:171","msg":"trace[1936537752] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1217; }","duration":"260.791683ms","start":"2026-05-28T21:47:07.114375Z","end":"2026-05-28T21:47:07.375167Z","steps":["trace[1936537752] 'read index received' (duration: 249.362945ms)","trace[1936537752] 'applied index is now lower than readState.Index' (duration: 11.427567ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:07.375341Z","caller":"traceutil/trace.go:171","msg":"trace[1449299241] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"287.84997ms","start":"2026-05-28T21:47:07.087457Z","end":"2026-05-28T21:47:07.375307Z","steps":["trace[1449299241] 'process raft request' (duration: 287.590684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:07.375413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.850021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:07.375477Z","caller":"traceutil/trace.go:171","msg":"trace[312406872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"176.951794ms","start":"2026-05-28T21:47:07.198508Z","end":"2026-05-28T21:47:07.37546Z","steps":["trace[312406872] 'agreement among raft nodes before linearized reading' (duration: 176.853751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:07.375579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.064769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:07.375632Z","caller":"traceutil/trace.go:171","msg":"trace[1159347188] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1168; }","duration":"261.288255ms","start":"2026-05-28T21:47:07.114331Z","end":"2026-05-28T21:47:07.375619Z","steps":["trace[1159347188] 'agreement among raft nodes before linearized reading' (duration: 260.957406ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:09.188763Z","caller":"traceutil/trace.go:171","msg":"trace[565494655] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"107.083773ms","start":"2026-05-28T21:47:09.081654Z","end":"2026-05-28T21:47:09.188738Z","steps":["trace[565494655] 'process raft request' (duration: 106.95288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:09.975687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.024369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T21:47:09.975745Z","caller":"traceutil/trace.go:171","msg":"trace[1550280424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"164.111582ms","start":"2026-05-28T21:47:09.811622Z","end":"2026-05-28T21:47:09.975733Z","steps":["trace[1550280424] 'range keys from in-memory index tree' (duration: 163.927917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:09.975949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.827251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-28T21:47:09.975989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.498336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:47:09.976011Z","caller":"traceutil/trace.go:171","msg":"trace[113796537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"149.540867ms","start":"2026-05-28T21:47:09.826464Z","end":"2026-05-28T21:47:09.976005Z","steps":["trace[113796537] 'range keys from in-memory index tree' (duration: 149.373523ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:09.976021Z","caller":"traceutil/trace.go:171","msg":"trace[1194761105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"146.926153ms","start":"2026-05-28T21:47:09.829078Z","end":"2026-05-28T21:47:09.976004Z","steps":["trace[1194761105] 'range keys from in-memory index tree' (duration: 146.685768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:11.9325Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483480714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:47:12.263513Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0712092s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T21:47:12.263758Z","caller":"traceutil/trace.go:171","msg":"trace[668141124] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"831.688975ms","start":"2026-05-28T21:47:11.432052Z","end":"2026-05-28T21:47:12.263741Z","steps":["trace[668141124] 'read index received' (duration: 831.542611ms)","trace[668141124] 'applied index is now lower than readState.Index' (duration: 145.744µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:12.263816Z","caller":"traceutil/trace.go:171","msg":"trace[1470467391] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"1.071587809s","start":"2026-05-28T21:47:11.192218Z","end":"2026-05-28T21:47:12.263806Z","steps":["trace[1470467391] 'process raft request' (duration: 1.071419835s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:12.263959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.776896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:12.263988Z","caller":"traceutil/trace.go:171","msg":"trace[1004545294] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1222; }","duration":"379.808486ms","start":"2026-05-28T21:47:11.88417Z","end":"2026-05-28T21:47:12.263979Z","steps":["trace[1004545294] 'agreement among raft nodes before linearized reading' (duration: 379.757365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:12.263993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.02968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:47:12.264014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:11.88411Z","time spent":"379.893359ms","remote":"127.0.0.1:38416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-05-28T21:47:12.264017Z","caller":"traceutil/trace.go:171","msg":"trace[939013796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1222; }","duration":"280.079871ms","start":"2026-05-28T21:47:11.98393Z","end":"2026-05-28T21:47:12.26401Z","steps":["trace[939013796] 'agreement among raft nodes before linearized reading' (duration: 279.985058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:12.263981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.146915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:47:12.264053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.004685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:47:12.264074Z","caller":"traceutil/trace.go:171","msg":"trace[541791305] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1222; }","duration":"505.253268ms","start":"2026-05-28T21:47:11.758792Z","end":"2026-05-28T21:47:12.264046Z","steps":["trace[541791305] 'agreement among raft nodes before linearized reading' (duration: 505.079683ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:12.264112Z","caller":"traceutil/trace.go:171","msg":"trace[2123002224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1222; }","duration":"279.100028ms","start":"2026-05-28T21:47:11.984997Z","end":"2026-05-28T21:47:12.264097Z","steps":["trace[2123002224] 'agreement among raft nodes before linearized reading' (duration: 278.893432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:12.264126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:11.758742Z","time spent":"505.373001ms","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:47:12.264174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.894297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:47:12.264199Z","caller":"traceutil/trace.go:171","msg":"trace[830854538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1222; }","duration":"279.955589ms","start":"2026-05-28T21:47:11.984237Z","end":"2026-05-28T21:47:12.264193Z","steps":["trace[830854538] 'agreement among raft nodes before linearized reading' (duration: 279.893367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:12.264272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.221609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:47:12.264293Z","caller":"traceutil/trace.go:171","msg":"trace[1083399911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"832.2678ms","start":"2026-05-28T21:47:11.432019Z","end":"2026-05-28T21:47:12.264287Z","steps":["trace[1083399911] 'agreement among raft nodes before linearized reading' (duration: 832.208588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:12.264311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:11.432006Z","time spent":"832.30045ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:47:12.263891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:11.192196Z","time spent":"1.071637731s","remote":"127.0.0.1:38522","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-05-28T21:47:12.264507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.889286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:47:12.264594Z","caller":"traceutil/trace.go:171","msg":"trace[1240512050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1222; }","duration":"136.04791ms","start":"2026-05-28T21:47:12.128526Z","end":"2026-05-28T21:47:12.264573Z","steps":["trace[1240512050] 'agreement among raft nodes before linearized reading' (duration: 135.737612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:13.191398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.63927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:13.191514Z","caller":"traceutil/trace.go:171","msg":"trace[256492567] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"922.858814ms","start":"2026-05-28T21:47:12.268633Z","end":"2026-05-28T21:47:13.191492Z","steps":["trace[256492567] 'process raft request' (duration: 444.046969ms)","trace[256492567] 'compare' (duration: 478.189989ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:13.191576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:12.268621Z","time spent":"922.926725ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:13.213783Z","caller":"traceutil/trace.go:171","msg":"trace[2144588581] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1275; }","duration":"840.813479ms","start":"2026-05-28T21:47:12.372946Z","end":"2026-05-28T21:47:13.21376Z","steps":["trace[2144588581] 'read index received' (duration: 339.744243ms)","trace[2144588581] 'applied index is now lower than readState.Index' (duration: 501.068036ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:13.213813Z","caller":"traceutil/trace.go:171","msg":"trace[1555240589] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"944.964902ms","start":"2026-05-28T21:47:12.268822Z","end":"2026-05-28T21:47:13.213786Z","steps":["trace[1555240589] 'process raft request' (duration: 944.87679ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:13.213798Z","caller":"traceutil/trace.go:171","msg":"trace[1133862621] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"945.112075ms","start":"2026-05-28T21:47:12.26865Z","end":"2026-05-28T21:47:13.213762Z","steps":["trace[1133862621] 'process raft request' (duration: 944.881719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:13.21397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:12.268813Z","time spent":"945.067194ms","remote":"127.0.0.1:38522","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-05-28T21:47:13.213995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.026054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:47:13.214141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:12.268634Z","time spent":"945.31378ms","remote":"127.0.0.1:38522","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-05-28T21:47:13.214589Z","caller":"traceutil/trace.go:171","msg":"trace[951041439] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"944.994782ms","start":"2026-05-28T21:47:12.268847Z","end":"2026-05-28T21:47:13.213842Z","steps":["trace[951041439] 'process raft request' (duration: 944.881889ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:13.214727Z","caller":"traceutil/trace.go:171","msg":"trace[1043341046] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"945.227437ms","start":"2026-05-28T21:47:12.268751Z","end":"2026-05-28T21:47:13.213979Z","steps":["trace[1043341046] 'process raft request' (duration: 944.914939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:13.21482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:12.268838Z","time spent":"945.868263ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:13.214837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:12.268737Z","time spent":"946.057478ms","remote":"127.0.0.1:38522","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-05-28T21:47:13.215024Z","caller":"traceutil/trace.go:171","msg":"trace[600293354] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1227; }","duration":"841.175517ms","start":"2026-05-28T21:47:12.372888Z","end":"2026-05-28T21:47:13.214063Z","steps":["trace[600293354] 'agreement among raft nodes before linearized reading' (duration: 841.010283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:13.215116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:12.372867Z","time spent":"842.226283ms","remote":"127.0.0.1:59228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-28T21:47:26.468414Z","caller":"traceutil/trace.go:171","msg":"trace[1953945379] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"133.401855ms","start":"2026-05-28T21:47:26.335Z","end":"2026-05-28T21:47:26.468402Z","steps":["trace[1953945379] 'process raft request' (duration: 133.316683ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:27.467013Z","caller":"traceutil/trace.go:171","msg":"trace[1233483186] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"150.690014ms","start":"2026-05-28T21:47:27.3163Z","end":"2026-05-28T21:47:27.46699Z","steps":["trace[1233483186] 'read index received' (duration: 150.473999ms)","trace[1233483186] 'applied index is now lower than readState.Index' (duration: 214.935µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:27.467076Z","caller":"traceutil/trace.go:171","msg":"trace[593100454] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"166.924139ms","start":"2026-05-28T21:47:27.300117Z","end":"2026-05-28T21:47:27.467041Z","steps":["trace[593100454] 'process raft request' (duration: 166.737514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:27.467229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.91828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:47:27.467268Z","caller":"traceutil/trace.go:171","msg":"trace[2132038135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"150.991332ms","start":"2026-05-28T21:47:27.316266Z","end":"2026-05-28T21:47:27.467258Z","steps":["trace[2132038135] 'agreement among raft nodes before linearized reading' (duration: 150.855079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:27.46726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.145992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T21:47:27.467517Z","caller":"traceutil/trace.go:171","msg":"trace[179010727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1277; }","duration":"143.434319ms","start":"2026-05-28T21:47:27.324074Z","end":"2026-05-28T21:47:27.467508Z","steps":["trace[179010727] 'agreement among raft nodes before linearized reading' (duration: 143.103681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:27.467599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.26928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-28T21:47:27.4677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.444006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:47:27.467746Z","caller":"traceutil/trace.go:171","msg":"trace[371006677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"140.506317ms","start":"2026-05-28T21:47:27.327228Z","end":"2026-05-28T21:47:27.467734Z","steps":["trace[371006677] 'agreement among raft nodes before linearized reading' (duration: 140.401244ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:27.467712Z","caller":"traceutil/trace.go:171","msg":"trace[1879193651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"142.367652ms","start":"2026-05-28T21:47:27.325275Z","end":"2026-05-28T21:47:27.467643Z","steps":["trace[1879193651] 'agreement among raft nodes before linearized reading' (duration: 142.136187ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:28.266196Z","caller":"traceutil/trace.go:171","msg":"trace[647548348] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"793.961269ms","start":"2026-05-28T21:47:27.472216Z","end":"2026-05-28T21:47:28.266178Z","steps":["trace[647548348] 'process raft request' (duration: 793.907428ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:28.266208Z","caller":"traceutil/trace.go:171","msg":"trace[1132587393] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"795.286151ms","start":"2026-05-28T21:47:27.470887Z","end":"2026-05-28T21:47:28.266173Z","steps":["trace[1132587393] 'process raft request' (duration: 788.930296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:28.2663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:27.472194Z","time spent":"794.049432ms","remote":"127.0.0.1:38522","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-05-28T21:47:28.266341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:27.470868Z","time spent":"795.399063ms","remote":"127.0.0.1:38522","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-05-28T21:47:28.266345Z","caller":"traceutil/trace.go:171","msg":"trace[1818812030] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"794.031691ms","start":"2026-05-28T21:47:27.472295Z","end":"2026-05-28T21:47:28.266327Z","steps":["trace[1818812030] 'process raft request' (duration: 793.861467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:28.266445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:27.472288Z","time spent":"794.103152ms","remote":"127.0.0.1:38522","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-05-28T21:47:28.266596Z","caller":"traceutil/trace.go:171","msg":"trace[1142526786] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"793.635561ms","start":"2026-05-28T21:47:27.472946Z","end":"2026-05-28T21:47:28.266582Z","steps":["trace[1142526786] 'process raft request' (duration: 793.327213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:28.266666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:27.472935Z","time spent":"793.694872ms","remote":"127.0.0.1:38522","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-05-28T21:47:29.629319Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156103125s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T21:47:29.62983Z","caller":"traceutil/trace.go:171","msg":"trace[2059192544] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"1.156805273s","start":"2026-05-28T21:47:28.472998Z","end":"2026-05-28T21:47:29.629803Z","steps":["trace[2059192544] 'process raft request' (duration: 1.156652788s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:29.630066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:28.472944Z","time spent":"1.156962776s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:29.649608Z","caller":"traceutil/trace.go:171","msg":"trace[1510508644] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"178.254935ms","start":"2026-05-28T21:47:29.471318Z","end":"2026-05-28T21:47:29.649573Z","steps":["trace[1510508644] 'read index received' (duration: 158.32894ms)","trace[1510508644] 'applied index is now lower than readState.Index' (duration: 19.924385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:29.649887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.551012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:47:29.649933Z","caller":"traceutil/trace.go:171","msg":"trace[1943828019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1283; }","duration":"178.646575ms","start":"2026-05-28T21:47:29.471278Z","end":"2026-05-28T21:47:29.649925Z","steps":["trace[1943828019] 'agreement among raft nodes before linearized reading' (duration: 178.401539ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:29.649655Z","caller":"traceutil/trace.go:171","msg":"trace[874599245] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"992.019546ms","start":"2026-05-28T21:47:28.657609Z","end":"2026-05-28T21:47:29.649629Z","steps":["trace[874599245] 'process raft request' (duration: 991.77269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:29.650369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:28.657585Z","time spent":"992.710813ms","remote":"127.0.0.1:38522","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-05-28T21:47:30.697672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483480857,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T21:47:30.982867Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.179076614s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-28T21:47:31.218103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41414087s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:31.218457Z","caller":"traceutil/trace.go:171","msg":"trace[2131491323] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"1.535640966s","start":"2026-05-28T21:47:29.682803Z","end":"2026-05-28T21:47:31.218444Z","steps":["trace[2131491323] 'process raft request' (duration: 1.535422511s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:31.218501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:29.682792Z","time spent":"1.535692828s","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:47:31.218477Z","caller":"traceutil/trace.go:171","msg":"trace[740811852] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"1.564624721s","start":"2026-05-28T21:47:29.653805Z","end":"2026-05-28T21:47:31.21843Z","steps":["trace[740811852] 'process raft request' (duration: 150.07449ms)","trace[740811852] 'compare' (duration: 1.413981086s)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:31.218814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:29.653768Z","time spent":"1.564898647s","remote":"127.0.0.1:38522","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-05-28T21:47:31.406839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.530484ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-28T21:47:31.40706Z","caller":"traceutil/trace.go:171","msg":"trace[820898576] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1339; }","duration":"1.209507674s","start":"2026-05-28T21:47:30.197534Z","end":"2026-05-28T21:47:31.407042Z","steps":["trace[820898576] 'read index received' (duration: 785.492443ms)","trace[820898576] 'applied index is now lower than readState.Index' (duration: 424.014211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:31.407191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209644658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:47:31.407229Z","caller":"traceutil/trace.go:171","msg":"trace[558806054] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1285; }","duration":"1.20973749s","start":"2026-05-28T21:47:30.197481Z","end":"2026-05-28T21:47:31.407218Z","steps":["trace[558806054] 'agreement among raft nodes before linearized reading' (duration: 1.209658058s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:31.407294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:30.19746Z","time spent":"1.209814242s","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:47:31.407398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209027293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:31.40752Z","caller":"traceutil/trace.go:171","msg":"trace[451802417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"1.209189837s","start":"2026-05-28T21:47:30.198306Z","end":"2026-05-28T21:47:31.407496Z","steps":["trace[451802417] 'agreement among raft nodes before linearized reading' (duration: 1.208963891s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:31.40758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:30.19829Z","time spent":"1.209273148s","remote":"127.0.0.1:38264","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-05-28T21:47:31.407769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136684724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:47:31.407796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136872107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-28T21:47:31.407826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136803407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:47:31.407835Z","caller":"traceutil/trace.go:171","msg":"trace[63863128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"1.136815067s","start":"2026-05-28T21:47:30.271003Z","end":"2026-05-28T21:47:31.407818Z","steps":["trace[63863128] 'agreement among raft nodes before linearized reading' (duration: 1.136628602s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:31.407851Z","caller":"traceutil/trace.go:171","msg":"trace[154062396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.13697953s","start":"2026-05-28T21:47:30.270857Z","end":"2026-05-28T21:47:31.407837Z","steps":["trace[154062396] 'agreement among raft nodes before linearized reading' (duration: 1.136791876s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:31.407863Z","caller":"traceutil/trace.go:171","msg":"trace[208881823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.137323838s","start":"2026-05-28T21:47:30.270528Z","end":"2026-05-28T21:47:31.407851Z","steps":["trace[208881823] 'agreement among raft nodes before linearized reading' (duration: 1.137201055s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:31.407872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:30.270977Z","time spent":"1.136887509s","remote":"127.0.0.1:38522","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-05-28T21:47:31.407884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:30.270835Z","time spent":"1.137043292s","remote":"127.0.0.1:38522","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-05-28T21:47:31.407892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:30.270497Z","time spent":"1.13738936s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T21:47:31.407883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137201565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:47:31.40795Z","caller":"traceutil/trace.go:171","msg":"trace[506546252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1286; }","duration":"1.137315958s","start":"2026-05-28T21:47:30.270617Z","end":"2026-05-28T21:47:31.407933Z","steps":["trace[506546252] 'agreement among raft nodes before linearized reading' (duration: 1.137089093s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:31.407947Z","caller":"traceutil/trace.go:171","msg":"trace[228793084] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"333.296596ms","start":"2026-05-28T21:47:31.074615Z","end":"2026-05-28T21:47:31.407912Z","steps":["trace[228793084] 'process raft request' (duration: 332.343513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:31.407993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:30.270576Z","time spent":"1.137409781s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T21:47:31.408107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:31.0746Z","time spent":"333.422078ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:31.841731Z","caller":"traceutil/trace.go:171","msg":"trace[127287622] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"420.557078ms","start":"2026-05-28T21:47:31.42115Z","end":"2026-05-28T21:47:31.841707Z","steps":["trace[127287622] 'process raft request' (duration: 420.413494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:31.841888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:31.421115Z","time spent":"420.70102ms","remote":"127.0.0.1:38522","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-05-28T21:47:32.81912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.221306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:32.819354Z","caller":"traceutil/trace.go:171","msg":"trace[1525777707] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"1.397837924s","start":"2026-05-28T21:47:31.421467Z","end":"2026-05-28T21:47:32.819305Z","steps":["trace[1525777707] 'process raft request' (duration: 684.357913ms)","trace[1525777707] 'compare' (duration: 712.854336ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:32.81939Z","caller":"traceutil/trace.go:171","msg":"trace[834071494] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"1.395456917s","start":"2026-05-28T21:47:31.423904Z","end":"2026-05-28T21:47:32.819361Z","steps":["trace[834071494] 'process raft request' (duration: 1.395371615s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:32.819432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395756653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:32.819434Z","caller":"traceutil/trace.go:171","msg":"trace[1217577233] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1343; }","duration":"1.395669791s","start":"2026-05-28T21:47:31.423671Z","end":"2026-05-28T21:47:32.819341Z","steps":["trace[1217577233] 'read index received' (duration: 417.966834ms)","trace[1217577233] 'applied index is now lower than readState.Index' (duration: 977.702117ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:32.819373Z","caller":"traceutil/trace.go:171","msg":"trace[868187889] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"1.397695561s","start":"2026-05-28T21:47:31.421629Z","end":"2026-05-28T21:47:32.819325Z","steps":["trace[868187889] 'process raft request' (duration: 1.397575138s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:32.81947Z","caller":"traceutil/trace.go:171","msg":"trace[1953350662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"1.395803625s","start":"2026-05-28T21:47:31.423654Z","end":"2026-05-28T21:47:32.819457Z","steps":["trace[1953350662] 'agreement among raft nodes before linearized reading' (duration: 1.395691022s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:32.819505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:31.423647Z","time spent":"1.395852096s","remote":"127.0.0.1:38264","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-05-28T21:47:32.819499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:31.421451Z","time spent":"1.397974717s","remote":"127.0.0.1:38522","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-05-28T21:47:32.819507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:31.421598Z","time spent":"1.397879285s","remote":"127.0.0.1:38522","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-05-28T21:47:32.819585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.348375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:47:32.819618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:31.42389Z","time spent":"1.395539098s","remote":"127.0.0.1:38522","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-05-28T21:47:32.819631Z","caller":"traceutil/trace.go:171","msg":"trace[1995650822] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1291; }","duration":"974.444828ms","start":"2026-05-28T21:47:31.845177Z","end":"2026-05-28T21:47:32.819621Z","steps":["trace[1995650822] 'agreement among raft nodes before linearized reading' (duration: 974.339885ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:32.819658Z","caller":"traceutil/trace.go:171","msg":"trace[549434299] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"1.183271247s","start":"2026-05-28T21:47:31.636377Z","end":"2026-05-28T21:47:32.819648Z","steps":["trace[549434299] 'process raft request' (duration: 1.182932318s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:32.819672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:31.845162Z","time spent":"974.50025ms","remote":"127.0.0.1:59202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:47:32.819705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:31.636351Z","time spent":"1.183330607s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:32.819679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.602012ms","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":"warn","ts":"2026-05-28T21:47:32.819747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.214814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:47:32.819776Z","caller":"traceutil/trace.go:171","msg":"trace[459080747] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1291; }","duration":"358.733004ms","start":"2026-05-28T21:47:32.461022Z","end":"2026-05-28T21:47:32.819755Z","steps":["trace[459080747] 'agreement among raft nodes before linearized reading' (duration: 358.597411ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:32.819795Z","caller":"traceutil/trace.go:171","msg":"trace[1798428016] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1291; }","duration":"692.358508ms","start":"2026-05-28T21:47:32.127424Z","end":"2026-05-28T21:47:32.819783Z","steps":["trace[1798428016] 'agreement among raft nodes before linearized reading' (duration: 692.267526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:32.819825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:32.127395Z","time spent":"692.42296ms","remote":"127.0.0.1:58464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:47:32.819824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:32.460998Z","time spent":"358.814887ms","remote":"127.0.0.1:47042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:47:34.511946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573465265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:47:34.511994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140893365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:47:34.512009Z","caller":"traceutil/trace.go:171","msg":"trace[765583302] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1291; }","duration":"1.573581048s","start":"2026-05-28T21:47:32.938415Z","end":"2026-05-28T21:47:34.511996Z","steps":["trace[765583302] 'count revisions from in-memory index tree' (duration: 1.573349452s)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:34.512035Z","caller":"traceutil/trace.go:171","msg":"trace[45248227] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1291; }","duration":"1.140977067s","start":"2026-05-28T21:47:33.371048Z","end":"2026-05-28T21:47:34.512025Z","steps":["trace[45248227] 'count revisions from in-memory index tree' (duration: 1.140815394s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:34.512029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.395821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-28T21:47:34.51204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:32.938396Z","time spent":"1.573638369s","remote":"127.0.0.1:58448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-05-28T21:47:34.5121Z","caller":"traceutil/trace.go:171","msg":"trace[756207112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"665.515094ms","start":"2026-05-28T21:47:33.846567Z","end":"2026-05-28T21:47:34.512082Z","steps":["trace[756207112] 'range keys from in-memory index tree' (duration: 665.130595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:34.512145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:33.846501Z","time spent":"665.630298ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T21:47:34.512438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290586026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:47:34.512514Z","caller":"traceutil/trace.go:171","msg":"trace[1104664569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"1.290711568s","start":"2026-05-28T21:47:33.221792Z","end":"2026-05-28T21:47:34.512503Z","steps":["trace[1104664569] 'range keys from in-memory index tree' (duration: 1.290439942s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:34.512533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.632715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:34.512552Z","caller":"traceutil/trace.go:171","msg":"trace[920216671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"315.675498ms","start":"2026-05-28T21:47:34.19687Z","end":"2026-05-28T21:47:34.512546Z","steps":["trace[920216671] 'range keys from in-memory index tree' (duration: 315.537713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:34.512558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:33.221772Z","time spent":"1.29076999s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:47:34.512572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:34.196856Z","time spent":"315.712468ms","remote":"127.0.0.1:38264","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-05-28T21:47:34.512064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:33.371028Z","time spent":"1.141029069s","remote":"127.0.0.1:38436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-28T21:47:34.675364Z","caller":"traceutil/trace.go:171","msg":"trace[89913959] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"158.654328ms","start":"2026-05-28T21:47:34.516683Z","end":"2026-05-28T21:47:34.675337Z","steps":["trace[89913959] 'process raft request' (duration: 158.493524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:35.324377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410706483480880,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T21:47:35.502812Z","caller":"traceutil/trace.go:171","msg":"trace[683516044] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"985.781674ms","start":"2026-05-28T21:47:34.517005Z","end":"2026-05-28T21:47:35.502786Z","steps":["trace[683516044] 'process raft request' (duration: 985.61528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:35.502948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:34.516995Z","time spent":"985.883756ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:35.512128Z","caller":"traceutil/trace.go:171","msg":"trace[633800691] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1349; }","duration":"688.093983ms","start":"2026-05-28T21:47:34.824016Z","end":"2026-05-28T21:47:35.51211Z","steps":["trace[633800691] 'read index received' (duration: 678.631363ms)","trace[633800691] 'applied index is now lower than readState.Index' (duration: 9.46195ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:35.512193Z","caller":"traceutil/trace.go:171","msg":"trace[302949491] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"688.046962ms","start":"2026-05-28T21:47:34.824113Z","end":"2026-05-28T21:47:35.51216Z","steps":["trace[302949491] 'process raft request' (duration: 687.892408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:35.5124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.872467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-28T21:47:35.512291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.270637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T21:47:35.512511Z","caller":"traceutil/trace.go:171","msg":"trace[1295305633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1294; }","duration":"687.95735ms","start":"2026-05-28T21:47:34.824491Z","end":"2026-05-28T21:47:35.512448Z","steps":["trace[1295305633] 'agreement among raft nodes before linearized reading' (duration: 687.800956ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:35.512526Z","caller":"traceutil/trace.go:171","msg":"trace[1095529454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1294; }","duration":"688.551454ms","start":"2026-05-28T21:47:34.82396Z","end":"2026-05-28T21:47:35.512512Z","steps":["trace[1095529454] 'agreement among raft nodes before linearized reading' (duration: 688.236596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:35.512541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:34.824096Z","time spent":"688.340579ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:35.512575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:34.823937Z","time spent":"688.627056ms","remote":"127.0.0.1:38522","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-05-28T21:47:35.512598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:34.824486Z","time spent":"688.066083ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T21:47:35.512384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.352463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T21:47:35.512672Z","caller":"traceutil/trace.go:171","msg":"trace[1913915586] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1294; }","duration":"360.697542ms","start":"2026-05-28T21:47:35.151968Z","end":"2026-05-28T21:47:35.512666Z","steps":["trace[1913915586] 'agreement among raft nodes before linearized reading' (duration: 360.303822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:35.512696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:35.151949Z","time spent":"360.741923ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-05-28T21:47:35.512384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.297298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:47:35.512763Z","caller":"traceutil/trace.go:171","msg":"trace[2029199817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1294; }","duration":"688.722059ms","start":"2026-05-28T21:47:34.824031Z","end":"2026-05-28T21:47:35.512753Z","steps":["trace[2029199817] 'agreement among raft nodes before linearized reading' (duration: 688.224306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:35.512798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:34.824009Z","time spent":"688.77982ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T21:47:35.664182Z","caller":"traceutil/trace.go:171","msg":"trace[313773180] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"148.348038ms","start":"2026-05-28T21:47:35.515811Z","end":"2026-05-28T21:47:35.664159Z","steps":["trace[313773180] 'process raft request' (duration: 148.149433ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:35.66495Z","caller":"traceutil/trace.go:171","msg":"trace[1360496272] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"149.059715ms","start":"2026-05-28T21:47:35.515862Z","end":"2026-05-28T21:47:35.664921Z","steps":["trace[1360496272] 'process raft request' (duration: 148.255976ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:35.666492Z","caller":"traceutil/trace.go:171","msg":"trace[1059245568] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"149.195218ms","start":"2026-05-28T21:47:35.517269Z","end":"2026-05-28T21:47:35.666464Z","steps":["trace[1059245568] 'process raft request' (duration: 147.573189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.481937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431624156s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:37.482075Z","caller":"traceutil/trace.go:171","msg":"trace[481687587] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1297; }","duration":"1.431785621s","start":"2026-05-28T21:47:36.050268Z","end":"2026-05-28T21:47:37.482053Z","steps":["trace[481687587] 'range keys from in-memory index tree' (duration: 1.431602465s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.482593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508548417s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:37.482685Z","caller":"traceutil/trace.go:171","msg":"trace[2081443728] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"1.765892386s","start":"2026-05-28T21:47:35.716775Z","end":"2026-05-28T21:47:37.482668Z","steps":["trace[2081443728] 'read index received' (duration: 257.215976ms)","trace[2081443728] 'applied index is now lower than readState.Index' (duration: 1.50867545s)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:37.482768Z","caller":"traceutil/trace.go:171","msg":"trace[184209002] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.855383902s","start":"2026-05-28T21:47:35.627373Z","end":"2026-05-28T21:47:37.482757Z","steps":["trace[184209002] 'process raft request' (duration: 346.591119ms)","trace[184209002] 'compare' (duration: 1.508446195s)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:37.482819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:35.627353Z","time spent":"1.855438523s","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:37.483089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657896479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:37.483202Z","caller":"traceutil/trace.go:171","msg":"trace[1441305095] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1298; }","duration":"1.658033022s","start":"2026-05-28T21:47:35.825115Z","end":"2026-05-28T21:47:37.483148Z","steps":["trace[1441305095] 'agreement among raft nodes before linearized reading' (duration: 1.657851908s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.483205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.200363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-28T21:47:37.483247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:35.825095Z","time spent":"1.658141504s","remote":"127.0.0.1:38430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-05-28T21:47:37.483265Z","caller":"traceutil/trace.go:171","msg":"trace[89181678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"803.336597ms","start":"2026-05-28T21:47:36.67991Z","end":"2026-05-28T21:47:37.483247Z","steps":["trace[89181678] 'agreement among raft nodes before linearized reading' (duration: 803.128512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.483312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:36.679892Z","time spent":"803.409018ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T21:47:37.483428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.766650774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:37.483476Z","caller":"traceutil/trace.go:171","msg":"trace[1569751049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"1.766745906s","start":"2026-05-28T21:47:35.716715Z","end":"2026-05-28T21:47:37.483461Z","steps":["trace[1569751049] 'agreement among raft nodes before linearized reading' (duration: 1.766666204s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.483511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:35.716698Z","time spent":"1.766806818s","remote":"127.0.0.1:38264","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-05-28T21:47:37.483458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285856661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:37.483575Z","caller":"traceutil/trace.go:171","msg":"trace[1833857876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"1.286014275s","start":"2026-05-28T21:47:36.197533Z","end":"2026-05-28T21:47:37.483547Z","steps":["trace[1833857876] 'agreement among raft nodes before linearized reading' (duration: 1.285833071s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.483638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:36.19751Z","time spent":"1.286114117s","remote":"127.0.0.1:38276","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-05-28T21:47:37.759106Z","caller":"traceutil/trace.go:171","msg":"trace[844299119] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"270.447936ms","start":"2026-05-28T21:47:37.488636Z","end":"2026-05-28T21:47:37.759084Z","steps":["trace[844299119] 'read index received' (duration: 270.369395ms)","trace[844299119] 'applied index is now lower than readState.Index' (duration: 77.641µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:37.75914Z","caller":"traceutil/trace.go:171","msg":"trace[299955099] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"271.331978ms","start":"2026-05-28T21:47:37.487778Z","end":"2026-05-28T21:47:37.75911Z","steps":["trace[299955099] 'process raft request' (duration: 271.172574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.75937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.716284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T21:47:37.759405Z","caller":"traceutil/trace.go:171","msg":"trace[715733198] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1299; }","duration":"270.786976ms","start":"2026-05-28T21:47:37.488609Z","end":"2026-05-28T21:47:37.759395Z","steps":["trace[715733198] 'agreement among raft nodes before linearized reading' (duration: 270.58198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.211125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.10107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:38.211243Z","caller":"traceutil/trace.go:171","msg":"trace[1889711575] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"452.051125ms","start":"2026-05-28T21:47:37.75917Z","end":"2026-05-28T21:47:38.211221Z","steps":["trace[1889711575] 'read index received' (duration: 176.76925ms)","trace[1889711575] 'applied index is now lower than readState.Index' (duration: 275.280315ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:38.211457Z","caller":"traceutil/trace.go:171","msg":"trace[680614052] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"689.663792ms","start":"2026-05-28T21:47:37.521665Z","end":"2026-05-28T21:47:38.211329Z","steps":["trace[680614052] 'process raft request' (duration: 414.261595ms)","trace[680614052] 'compare' (duration: 274.968847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:38.21157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.52165Z","time spent":"689.853497ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:38.211616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.380234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-28T21:47:38.211637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.78102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T21:47:38.21167Z","caller":"traceutil/trace.go:171","msg":"trace[45976578] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1300; }","duration":"450.480955ms","start":"2026-05-28T21:47:37.761175Z","end":"2026-05-28T21:47:38.211656Z","steps":["trace[45976578] 'agreement among raft nodes before linearized reading' (duration: 450.355982ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:38.211679Z","caller":"traceutil/trace.go:171","msg":"trace[1972883211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1300; }","duration":"704.845691ms","start":"2026-05-28T21:47:37.506822Z","end":"2026-05-28T21:47:38.211667Z","steps":["trace[1972883211] 'agreement among raft nodes before linearized reading' (duration: 704.682028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.211716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.761159Z","time spent":"450.548077ms","remote":"127.0.0.1:38532","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":"warn","ts":"2026-05-28T21:47:38.21173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.506812Z","time spent":"704.910844ms","remote":"127.0.0.1:38522","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-05-28T21:47:38.211725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.324276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:47:38.211805Z","caller":"traceutil/trace.go:171","msg":"trace[931595602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"539.451029ms","start":"2026-05-28T21:47:37.672333Z","end":"2026-05-28T21:47:38.211784Z","steps":["trace[931595602] 'agreement among raft nodes before linearized reading' (duration: 539.227714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.211855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.137119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-28T21:47:38.211861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.672313Z","time spent":"539.535812ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T21:47:38.211881Z","caller":"traceutil/trace.go:171","msg":"trace[1900984140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1300; }","duration":"543.199ms","start":"2026-05-28T21:47:37.668673Z","end":"2026-05-28T21:47:38.211872Z","steps":["trace[1900984140] 'agreement among raft nodes before linearized reading' (duration: 543.123369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.211906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.668651Z","time spent":"543.249781ms","remote":"127.0.0.1:38522","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-05-28T21:47:38.211926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.00915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:47:38.211959Z","caller":"traceutil/trace.go:171","msg":"trace[217627585] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1300; }","duration":"220.084102ms","start":"2026-05-28T21:47:37.991865Z","end":"2026-05-28T21:47:38.211949Z","steps":["trace[217627585] 'agreement among raft nodes before linearized reading' (duration: 220.021891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.211929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.050755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:38.212019Z","caller":"traceutil/trace.go:171","msg":"trace[560067310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"721.168018ms","start":"2026-05-28T21:47:37.490828Z","end":"2026-05-28T21:47:38.211996Z","steps":["trace[560067310] 'agreement among raft nodes before linearized reading' (duration: 720.979034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.212065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.490812Z","time spent":"721.245ms","remote":"127.0.0.1:38264","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-05-28T21:47:38.212062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.620019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:47:38.212117Z","caller":"traceutil/trace.go:171","msg":"trace[1475370507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1300; }","duration":"538.69393ms","start":"2026-05-28T21:47:37.67341Z","end":"2026-05-28T21:47:38.212104Z","steps":["trace[1475370507] 'agreement among raft nodes before linearized reading' (duration: 538.565427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.212155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.673398Z","time spent":"538.747182ms","remote":"127.0.0.1:38522","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-05-28T21:47:40.37585Z","caller":"traceutil/trace.go:171","msg":"trace[482005753] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"107.205639ms","start":"2026-05-28T21:47:40.268623Z","end":"2026-05-28T21:47:40.375828Z","steps":["trace[482005753] 'read index received' (duration: 106.942041ms)","trace[482005753] 'applied index is now lower than readState.Index' (duration: 262.658µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:40.376029Z","caller":"traceutil/trace.go:171","msg":"trace[1992445827] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"159.90342ms","start":"2026-05-28T21:47:40.216079Z","end":"2026-05-28T21:47:40.375982Z","steps":["trace[1992445827] 'process raft request' (duration: 159.587762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:40.376383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.67621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:47:40.376429Z","caller":"traceutil/trace.go:171","msg":"trace[2009332144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"107.852944ms","start":"2026-05-28T21:47:40.268565Z","end":"2026-05-28T21:47:40.376418Z","steps":["trace[2009332144] 'agreement among raft nodes before linearized reading' (duration: 107.419913ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:42.040055Z","caller":"traceutil/trace.go:171","msg":"trace[89714766] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"268.600922ms","start":"2026-05-28T21:47:41.771422Z","end":"2026-05-28T21:47:42.040023Z","steps":["trace[89714766] 'read index received' (duration: 268.308994ms)","trace[89714766] 'applied index is now lower than readState.Index' (duration: 290.828µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:42.04032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.881419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:47:42.040415Z","caller":"traceutil/trace.go:171","msg":"trace[1137333292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1330; }","duration":"268.989512ms","start":"2026-05-28T21:47:41.771375Z","end":"2026-05-28T21:47:42.040364Z","steps":["trace[1137333292] 'agreement among raft nodes before linearized reading' (duration: 268.754456ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:42.040526Z","caller":"traceutil/trace.go:171","msg":"trace[516696954] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"317.202385ms","start":"2026-05-28T21:47:41.723202Z","end":"2026-05-28T21:47:42.040405Z","steps":["trace[516696954] 'process raft request' (duration: 316.6138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:42.040643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:41.723176Z","time spent":"317.40307ms","remote":"127.0.0.1:38522","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-05-28T21:47:42.584306Z","caller":"traceutil/trace.go:171","msg":"trace[1978583211] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"538.865366ms","start":"2026-05-28T21:47:42.045392Z","end":"2026-05-28T21:47:42.584258Z","steps":["trace[1978583211] 'process raft request' (duration: 534.288414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:42.584431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:42.045365Z","time spent":"539.000099ms","remote":"127.0.0.1:38522","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-05-28T21:47:42.590005Z","caller":"traceutil/trace.go:171","msg":"trace[1140958660] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1389; }","duration":"203.684774ms","start":"2026-05-28T21:47:42.386305Z","end":"2026-05-28T21:47:42.58999Z","steps":["trace[1140958660] 'read index received' (duration: 193.070306ms)","trace[1140958660] 'applied index is now lower than readState.Index' (duration: 10.613388ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:42.590077Z","caller":"traceutil/trace.go:171","msg":"trace[1105483234] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"207.804973ms","start":"2026-05-28T21:47:42.382238Z","end":"2026-05-28T21:47:42.590043Z","steps":["trace[1105483234] 'process raft request' (duration: 207.67075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:42.590299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.574326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:47:42.59049Z","caller":"traceutil/trace.go:171","msg":"trace[931978249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"202.783701ms","start":"2026-05-28T21:47:42.387688Z","end":"2026-05-28T21:47:42.590472Z","steps":["trace[931978249] 'agreement among raft nodes before linearized reading' (duration: 202.496275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:42.590284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.635848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:47:42.590646Z","caller":"traceutil/trace.go:171","msg":"trace[971843081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"203.050187ms","start":"2026-05-28T21:47:42.387587Z","end":"2026-05-28T21:47:42.590637Z","steps":["trace[971843081] 'agreement among raft nodes before linearized reading' (duration: 202.523615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:42.590635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.639235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T21:47:42.590791Z","caller":"traceutil/trace.go:171","msg":"trace[62441607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"199.908002ms","start":"2026-05-28T21:47:42.390818Z","end":"2026-05-28T21:47:42.590726Z","steps":["trace[62441607] 'agreement among raft nodes before linearized reading' (duration: 199.339268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:42.591011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.707068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:47:42.591057Z","caller":"traceutil/trace.go:171","msg":"trace[1489555222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"204.7911ms","start":"2026-05-28T21:47:42.386256Z","end":"2026-05-28T21:47:42.591047Z","steps":["trace[1489555222] 'agreement among raft nodes before linearized reading' (duration: 204.131734ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:44.719289Z","caller":"traceutil/trace.go:171","msg":"trace[2058474594] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"125.120443ms","start":"2026-05-28T21:47:44.59414Z","end":"2026-05-28T21:47:44.71926Z","steps":["trace[2058474594] 'process raft request' (duration: 124.919378ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:44.7204Z","caller":"traceutil/trace.go:171","msg":"trace[2030823636] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1409; }","duration":"119.542396ms","start":"2026-05-28T21:47:44.600839Z","end":"2026-05-28T21:47:44.720381Z","steps":["trace[2030823636] 'read index received' (duration: 118.00067ms)","trace[2030823636] 'applied index is now lower than readState.Index' (duration: 1.540156ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:44.720615Z","caller":"traceutil/trace.go:171","msg":"trace[763243523] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"125.266246ms","start":"2026-05-28T21:47:44.595301Z","end":"2026-05-28T21:47:44.720567Z","steps":["trace[763243523] 'process raft request' (duration: 124.745593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:44.72083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.340809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:47:44.720953Z","caller":"traceutil/trace.go:171","msg":"trace[1481472405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1352; }","duration":"118.499233ms","start":"2026-05-28T21:47:44.602434Z","end":"2026-05-28T21:47:44.720933Z","steps":["trace[1481472405] 'agreement among raft nodes before linearized reading' (duration: 118.189754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:44.720901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.348579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-28T21:47:44.721195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.677667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:47:44.721257Z","caller":"traceutil/trace.go:171","msg":"trace[1934267037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1352; }","duration":"118.750858ms","start":"2026-05-28T21:47:44.602492Z","end":"2026-05-28T21:47:44.721243Z","steps":["trace[1934267037] 'agreement among raft nodes before linearized reading' (duration: 118.578854ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:44.721231Z","caller":"traceutil/trace.go:171","msg":"trace[838604231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1352; }","duration":"118.689776ms","start":"2026-05-28T21:47:44.602524Z","end":"2026-05-28T21:47:44.721214Z","steps":["trace[838604231] 'agreement among raft nodes before linearized reading' (duration: 118.285947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:44.721584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.751636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T21:47:44.7217Z","caller":"traceutil/trace.go:171","msg":"trace[1912961092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1352; }","duration":"120.89564ms","start":"2026-05-28T21:47:44.60079Z","end":"2026-05-28T21:47:44.721685Z","steps":["trace[1912961092] 'agreement among raft nodes before linearized reading' (duration: 119.754231ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:49.855744Z","caller":"traceutil/trace.go:171","msg":"trace[787040204] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1452; }","duration":"123.736641ms","start":"2026-05-28T21:47:49.731986Z","end":"2026-05-28T21:47:49.855723Z","steps":["trace[787040204] 'read index received' (duration: 123.678808ms)","trace[787040204] 'applied index is now lower than readState.Index' (duration: 56.523µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:49.855946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.944915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-28T21:47:49.855982Z","caller":"traceutil/trace.go:171","msg":"trace[1348013873] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"124.034308ms","start":"2026-05-28T21:47:49.731937Z","end":"2026-05-28T21:47:49.855972Z","steps":["trace[1348013873] 'agreement among raft nodes before linearized reading' (duration: 123.872004ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:49.855931Z","caller":"traceutil/trace.go:171","msg":"trace[1640353536] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"327.160926ms","start":"2026-05-28T21:47:49.528748Z","end":"2026-05-28T21:47:49.855909Z","steps":["trace[1640353536] 'process raft request' (duration: 326.839397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:49.856185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:49.528727Z","time spent":"327.338361ms","remote":"127.0.0.1:38522","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-05-28T21:48:07.035154Z","caller":"traceutil/trace.go:171","msg":"trace[943282738] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"158.252298ms","start":"2026-05-28T21:48:06.876877Z","end":"2026-05-28T21:48:07.035129Z","steps":["trace[943282738] 'process raft request' (duration: 158.083554ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:07.035323Z","caller":"traceutil/trace.go:171","msg":"trace[611026103] linearizableReadLoop","detail":"{readStateIndex:1539; appliedIndex:1536; }","duration":"116.622387ms","start":"2026-05-28T21:48:06.918686Z","end":"2026-05-28T21:48:07.035309Z","steps":["trace[611026103] 'read index received' (duration: 27.697254ms)","trace[611026103] 'applied index is now lower than readState.Index' (duration: 88.923983ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:48:07.035407Z","caller":"traceutil/trace.go:171","msg":"trace[237828833] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"157.291275ms","start":"2026-05-28T21:48:06.878089Z","end":"2026-05-28T21:48:07.03538Z","steps":["trace[237828833] 'process raft request' (duration: 156.995818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:07.035472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.779311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:48:07.035533Z","caller":"traceutil/trace.go:171","msg":"trace[2011702943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1475; }","duration":"116.893463ms","start":"2026-05-28T21:48:06.918632Z","end":"2026-05-28T21:48:07.035525Z","steps":["trace[2011702943] 'agreement among raft nodes before linearized reading' (duration: 116.718339ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:15.626159Z","caller":"traceutil/trace.go:171","msg":"trace[2120771768] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"131.328224ms","start":"2026-05-28T21:48:15.494806Z","end":"2026-05-28T21:48:15.626134Z","steps":["trace[2120771768] 'process raft request' (duration: 131.1838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:20.911437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.342231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:48:20.911587Z","caller":"traceutil/trace.go:171","msg":"trace[1726301951] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"218.790229ms","start":"2026-05-28T21:48:20.69277Z","end":"2026-05-28T21:48:20.911561Z","steps":["trace[1726301951] 'process raft request' (duration: 90.220003ms)","trace[1726301951] 'compare' (duration: 128.178147ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:48:21.222585Z","caller":"traceutil/trace.go:171","msg":"trace[1628865653] linearizableReadLoop","detail":"{readStateIndex:1604; appliedIndex:1603; }","duration":"210.604124ms","start":"2026-05-28T21:48:21.011951Z","end":"2026-05-28T21:48:21.222555Z","steps":["trace[1628865653] 'read index received' (duration: 208.330271ms)","trace[1628865653] 'applied index is now lower than readState.Index' (duration: 2.272503ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:48:21.222665Z","caller":"traceutil/trace.go:171","msg":"trace[101506695] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"289.297092ms","start":"2026-05-28T21:48:20.933328Z","end":"2026-05-28T21:48:21.222625Z","steps":["trace[101506695] 'process raft request' (duration: 287.033968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:21.22286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.895752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:48:21.222899Z","caller":"traceutil/trace.go:171","msg":"trace[2090463219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1537; }","duration":"210.980834ms","start":"2026-05-28T21:48:21.011905Z","end":"2026-05-28T21:48:21.222886Z","steps":["trace[2090463219] 'agreement among raft nodes before linearized reading' (duration: 210.80914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:21.222921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.306566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-28T21:48:21.22301Z","caller":"traceutil/trace.go:171","msg":"trace[136180407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1537; }","duration":"124.450459ms","start":"2026-05-28T21:48:21.098536Z","end":"2026-05-28T21:48:21.222987Z","steps":["trace[136180407] 'agreement among raft nodes before linearized reading' (duration: 124.248364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:21.223201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.33724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T21:48:21.223237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.838406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T21:48:21.223265Z","caller":"traceutil/trace.go:171","msg":"trace[1305316488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1537; }","duration":"117.442002ms","start":"2026-05-28T21:48:21.105806Z","end":"2026-05-28T21:48:21.223248Z","steps":["trace[1305316488] 'agreement among raft nodes before linearized reading' (duration: 117.262738ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:21.223288Z","caller":"traceutil/trace.go:171","msg":"trace[1047127119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1537; }","duration":"119.01612ms","start":"2026-05-28T21:48:21.104257Z","end":"2026-05-28T21:48:21.223273Z","steps":["trace[1047127119] 'agreement among raft nodes before linearized reading' (duration: 118.894877ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:21.461936Z","caller":"traceutil/trace.go:171","msg":"trace[413224251] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"234.490365ms","start":"2026-05-28T21:48:21.227411Z","end":"2026-05-28T21:48:21.461901Z","steps":["trace[413224251] 'process raft request' (duration: 223.353719ms)","trace[413224251] 'compare' (duration: 10.821078ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:48:21.461985Z","caller":"traceutil/trace.go:171","msg":"trace[877775111] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"234.150467ms","start":"2026-05-28T21:48:21.227809Z","end":"2026-05-28T21:48:21.461959Z","steps":["trace[877775111] 'process raft request' (duration: 234.091105ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:21.462071Z","caller":"traceutil/trace.go:171","msg":"trace[894522882] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"234.561996ms","start":"2026-05-28T21:48:21.227493Z","end":"2026-05-28T21:48:21.462055Z","steps":["trace[894522882] 'process raft request' (duration: 234.31579ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:55.333278Z","caller":"traceutil/trace.go:171","msg":"trace[1510940358] linearizableReadLoop","detail":"{readStateIndex:1765; appliedIndex:1764; }","duration":"177.126166ms","start":"2026-05-28T21:48:55.156118Z","end":"2026-05-28T21:48:55.333244Z","steps":["trace[1510940358] 'read index received' (duration: 114.930932ms)","trace[1510940358] 'applied index is now lower than readState.Index' (duration: 62.194334ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:48:55.333386Z","caller":"traceutil/trace.go:171","msg":"trace[210043087] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"179.231956ms","start":"2026-05-28T21:48:55.154142Z","end":"2026-05-28T21:48:55.333374Z","steps":["trace[210043087] 'process raft request' (duration: 116.972001ms)","trace[210043087] 'compare' (duration: 62.04906ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:48:55.333556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.434083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T21:48:55.333585Z","caller":"traceutil/trace.go:171","msg":"trace[1773700233] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1692; }","duration":"177.500365ms","start":"2026-05-28T21:48:55.156076Z","end":"2026-05-28T21:48:55.333576Z","steps":["trace[1773700233] 'agreement among raft nodes before linearized reading' (duration: 177.398182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:55.333728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.308436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:48:55.333755Z","caller":"traceutil/trace.go:171","msg":"trace[1051148237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1692; }","duration":"137.372138ms","start":"2026-05-28T21:48:55.196374Z","end":"2026-05-28T21:48:55.333746Z","steps":["trace[1051148237] 'agreement among raft nodes before linearized reading' (duration: 137.334147ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:49:17.635949Z","caller":"traceutil/trace.go:171","msg":"trace[1295752554] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"156.839318ms","start":"2026-05-28T21:49:17.479079Z","end":"2026-05-28T21:49:17.635918Z","steps":["trace[1295752554] 'process raft request' (duration: 156.652814ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:49:47.990868Z","caller":"traceutil/trace.go:171","msg":"trace[407813094] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"126.898403ms","start":"2026-05-28T21:49:47.863946Z","end":"2026-05-28T21:49:47.990845Z","steps":["trace[407813094] 'process raft request' (duration: 59.596123ms)","trace[407813094] 'compare' (duration: 67.149326ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:49:48.213905Z","caller":"traceutil/trace.go:171","msg":"trace[1515894126] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"218.314217ms","start":"2026-05-28T21:49:47.995559Z","end":"2026-05-28T21:49:48.213873Z","steps":["trace[1515894126] 'process raft request' (duration: 157.461883ms)","trace[1515894126] 'compare' (duration: 60.594897ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:49:48.214301Z","caller":"traceutil/trace.go:171","msg":"trace[1887106665] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2017; }","duration":"218.346308ms","start":"2026-05-28T21:49:47.995931Z","end":"2026-05-28T21:49:48.214277Z","steps":["trace[1887106665] 'read index received' (duration: 31.998534ms)","trace[1887106665] 'applied index is now lower than readState.Index' (duration: 186.346354ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:49:48.214358Z","caller":"traceutil/trace.go:171","msg":"trace[2129230232] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"218.645635ms","start":"2026-05-28T21:49:47.995687Z","end":"2026-05-28T21:49:48.214333Z","steps":["trace[2129230232] 'process raft request' (duration: 218.133502ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:49:48.214447Z","caller":"traceutil/trace.go:171","msg":"trace[1826325903] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"218.171033ms","start":"2026-05-28T21:49:47.996263Z","end":"2026-05-28T21:49:48.214434Z","steps":["trace[1826325903] 'process raft request' (duration: 217.824075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:48.214671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.692077ms","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-05-28T21:49:48.214808Z","caller":"traceutil/trace.go:171","msg":"trace[1666391262] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1937; }","duration":"218.872471ms","start":"2026-05-28T21:49:47.995903Z","end":"2026-05-28T21:49:48.214775Z","steps":["trace[1666391262] 'agreement among raft nodes before linearized reading' (duration: 218.651155ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:50:12.280131Z","caller":"traceutil/trace.go:171","msg":"trace[330567815] linearizableReadLoop","detail":"{readStateIndex:2131; appliedIndex:2130; }","duration":"104.4887ms","start":"2026-05-28T21:50:12.175624Z","end":"2026-05-28T21:50:12.280113Z","steps":["trace[330567815] 'read index received' (duration: 104.386178ms)","trace[330567815] 'applied index is now lower than readState.Index' (duration: 101.762µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:50:12.280182Z","caller":"traceutil/trace.go:171","msg":"trace[2098300991] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"237.169173ms","start":"2026-05-28T21:50:12.042979Z","end":"2026-05-28T21:50:12.280148Z","steps":["trace[2098300991] 'process raft request' (duration: 237.02101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:50:12.280309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.677644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:50:12.280332Z","caller":"traceutil/trace.go:171","msg":"trace[7425390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2042; }","duration":"104.753906ms","start":"2026-05-28T21:50:12.175571Z","end":"2026-05-28T21:50:12.280325Z","steps":["trace[7425390] 'agreement among raft nodes before linearized reading' (duration: 104.642884ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:50:12.488558Z","caller":"traceutil/trace.go:171","msg":"trace[852094425] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"101.524599ms","start":"2026-05-28T21:50:12.387009Z","end":"2026-05-28T21:50:12.488533Z","steps":["trace[852094425] 'process raft request' (duration: 101.479828ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:50:12.488627Z","caller":"traceutil/trace.go:171","msg":"trace[87748431] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"101.827136ms","start":"2026-05-28T21:50:12.38677Z","end":"2026-05-28T21:50:12.488597Z","steps":["trace[87748431] 'process raft request' (duration: 96.086247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:50:49.065824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.358487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:50:49.065951Z","caller":"traceutil/trace.go:171","msg":"trace[2115384733] linearizableReadLoop","detail":"{readStateIndex:2309; appliedIndex:2308; }","duration":"338.711318ms","start":"2026-05-28T21:50:48.727219Z","end":"2026-05-28T21:50:49.065931Z","steps":["trace[2115384733] 'read index received' (duration: 207.090485ms)","trace[2115384733] 'applied index is now lower than readState.Index' (duration: 131.619423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:50:49.066133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.66898ms","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-05-28T21:50:49.066191Z","caller":"traceutil/trace.go:171","msg":"trace[1452230257] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2213; }","duration":"320.753203ms","start":"2026-05-28T21:50:48.745421Z","end":"2026-05-28T21:50:49.066174Z","steps":["trace[1452230257] 'agreement among raft nodes before linearized reading' (duration: 320.63687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:50:49.06624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:50:48.745408Z","time spent":"320.816394ms","remote":"127.0.0.1:47042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:50:49.066258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.319851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-28T21:50:49.066354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.126178ms","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-05-28T21:50:49.066417Z","caller":"traceutil/trace.go:171","msg":"trace[1630991413] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2213; }","duration":"165.560017ms","start":"2026-05-28T21:50:48.900837Z","end":"2026-05-28T21:50:49.066397Z","steps":["trace[1630991413] 'agreement among raft nodes before linearized reading' (duration: 165.284581ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:50:49.066514Z","caller":"traceutil/trace.go:171","msg":"trace[808868538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2213; }","duration":"339.300632ms","start":"2026-05-28T21:50:48.727195Z","end":"2026-05-28T21:50:49.066496Z","steps":["trace[808868538] 'agreement among raft nodes before linearized reading' (duration: 338.842142ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:50:49.066524Z","caller":"traceutil/trace.go:171","msg":"trace[1521450311] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"341.588277ms","start":"2026-05-28T21:50:48.724901Z","end":"2026-05-28T21:50:49.06649Z","steps":["trace[1521450311] 'process raft request' (duration: 209.469912ms)","trace[1521450311] 'compare' (duration: 131.244284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:50:49.066611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:50:48.727188Z","time spent":"339.405965ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T21:50:49.06669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:50:48.724883Z","time spent":"341.723851ms","remote":"127.0.0.1:38522","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-05-28T21:51:27.511495Z","caller":"traceutil/trace.go:171","msg":"trace[464206170] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"223.247061ms","start":"2026-05-28T21:51:27.288213Z","end":"2026-05-28T21:51:27.51146Z","steps":["trace[464206170] 'process raft request' (duration: 223.033115ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:27.511439Z","caller":"traceutil/trace.go:171","msg":"trace[2068518058] linearizableReadLoop","detail":"{readStateIndex:2515; appliedIndex:2514; }","duration":"210.163185ms","start":"2026-05-28T21:51:27.301252Z","end":"2026-05-28T21:51:27.511415Z","steps":["trace[2068518058] 'read index received' (duration: 209.993611ms)","trace[2068518058] 'applied index is now lower than readState.Index' (duration: 168.464µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:51:27.511732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.466883ms","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-05-28T21:51:27.511773Z","caller":"traceutil/trace.go:171","msg":"trace[2061479954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2410; }","duration":"210.566545ms","start":"2026-05-28T21:51:27.301194Z","end":"2026-05-28T21:51:27.51176Z","steps":["trace[2061479954] 'agreement among raft nodes before linearized reading' (duration: 210.402991ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:32.980219Z","caller":"traceutil/trace.go:171","msg":"trace[890915533] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"243.012958ms","start":"2026-05-28T21:51:32.737174Z","end":"2026-05-28T21:51:32.980187Z","steps":["trace[890915533] 'process raft request' (duration: 242.705181ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:33.654952Z","caller":"traceutil/trace.go:171","msg":"trace[1526078941] linearizableReadLoop","detail":"{readStateIndex:2547; appliedIndex:2546; }","duration":"115.218673ms","start":"2026-05-28T21:51:33.539713Z","end":"2026-05-28T21:51:33.654931Z","steps":["trace[1526078941] 'read index received' (duration: 115.12987ms)","trace[1526078941] 'applied index is now lower than readState.Index' (duration: 87.993µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:51:33.655312Z","caller":"traceutil/trace.go:171","msg":"trace[1675236124] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"116.582375ms","start":"2026-05-28T21:51:33.538698Z","end":"2026-05-28T21:51:33.65528Z","steps":["trace[1675236124] 'process raft request' (duration: 116.117453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:33.655459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.719385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T21:51:33.655547Z","caller":"traceutil/trace.go:171","msg":"trace[1199519521] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2441; }","duration":"115.841627ms","start":"2026-05-28T21:51:33.539682Z","end":"2026-05-28T21:51:33.655523Z","steps":["trace[1199519521] 'agreement among raft nodes before linearized reading' (duration: 115.576981ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:51:33.975261Z","caller":"traceutil/trace.go:171","msg":"trace[36253060] linearizableReadLoop","detail":"{readStateIndex:2548; appliedIndex:2547; }","duration":"320.072398ms","start":"2026-05-28T21:51:33.65512Z","end":"2026-05-28T21:51:33.975192Z","steps":["trace[36253060] 'read index received' (duration: 319.887624ms)","trace[36253060] 'applied index is now lower than readState.Index' (duration: 183.804µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:51:33.975299Z","caller":"traceutil/trace.go:171","msg":"trace[1789059384] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"432.929134ms","start":"2026-05-28T21:51:33.542345Z","end":"2026-05-28T21:51:33.975274Z","steps":["trace[1789059384] 'process raft request' (duration: 432.690358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:33.975461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.073844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:51:33.975507Z","caller":"traceutil/trace.go:171","msg":"trace[901489143] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2442; }","duration":"372.174426ms","start":"2026-05-28T21:51:33.603319Z","end":"2026-05-28T21:51:33.975494Z","steps":["trace[901489143] 'agreement among raft nodes before linearized reading' (duration: 372.061424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:33.975547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:33.60329Z","time spent":"372.241408ms","remote":"127.0.0.1:38732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-28T21:51:33.975704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.79549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-28T21:51:33.975722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:33.542325Z","time spent":"433.034696ms","remote":"127.0.0.1:38522","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-05-28T21:51:33.975754Z","caller":"traceutil/trace.go:171","msg":"trace[709911690] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2442; }","duration":"432.877642ms","start":"2026-05-28T21:51:33.542863Z","end":"2026-05-28T21:51:33.975741Z","steps":["trace[709911690] 'agreement among raft nodes before linearized reading' (duration: 432.716128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:33.975788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:33.542849Z","time spent":"432.931293ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-28T21:51:33.988821Z","caller":"traceutil/trace.go:171","msg":"trace[1478040578] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"328.401611ms","start":"2026-05-28T21:51:33.6604Z","end":"2026-05-28T21:51:33.988801Z","steps":["trace[1478040578] 'process raft request' (duration: 328.296799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:51:33.988959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:51:33.660374Z","time spent":"328.509164ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:51:33.989089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.643648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:51:33.989324Z","caller":"traceutil/trace.go:171","msg":"trace[1843760500] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2443; }","duration":"104.923664ms","start":"2026-05-28T21:51:33.884289Z","end":"2026-05-28T21:51:33.989213Z","steps":["trace[1843760500] 'agreement among raft nodes before linearized reading' (duration: 104.530514ms)"],"step_count":1}