{"level":"warn","ts":"2026-03-13T17:11:42.911977Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T17:11:42.912057Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-03-13T17:11:42.91215Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T17:11:42.912164Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-03-13T17:11:42.912181Z","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-03-13T17:11:42.916208Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-03-13T17:11:42.916293Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-03-13T17:11:42.920947Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.45326ms"} {"level":"info","ts":"2026-03-13T17:11:42.926688Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-03-13T17:11:42.926762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T17:11:42.926785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-03-13T17:11:42.926798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T17:11:42.926813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-03-13T17:11:42.926852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-03-13T17:11:42.938302Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T17:11:42.939445Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T17:11:42.940599Z","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-03-13T17:11:42.942642Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T17:11:42.942844Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T17:11:42.943009Z","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-03-13T17:11:42.943106Z","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-03-13T17:11:42.943117Z","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-03-13T17:11:42.944967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-03-13T17:11:42.945504Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-03-13T17:11:42.949248Z","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-03-13T17:11:42.949324Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-03-13T17:11:42.949393Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-03-13T17:11:42.949603Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T17:11:42.949633Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T17:11:43.127423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T17:11:43.1275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T17:11:43.127527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-03-13T17:11:43.127704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-03-13T17:11:43.127724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-03-13T17:11:43.127738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-03-13T17:11:43.127754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-03-13T17:11:43.128954Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:11:43.130055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T17:11:43.130036Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T17:11:43.130408Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T17:11:43.130713Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:11:43.130861Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:11:43.13093Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:11:43.130942Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T17:11:43.130985Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T17:11:43.133307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-03-13T17:11:43.133494Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-13T17:11:48.048576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.529525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T17:11:48.048661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.618489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-13T17:11:48.048745Z","caller":"traceutil/trace.go:171","msg":"trace[1994261165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:235; }","duration":"172.776571ms","start":"2026-03-13T17:11:47.875945Z","end":"2026-03-13T17:11:48.048721Z","steps":["trace[1994261165] 'range keys from in-memory index tree' (duration: 172.382272ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:11:48.048808Z","caller":"traceutil/trace.go:171","msg":"trace[1626259257] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:235; }","duration":"182.756993ms","start":"2026-03-13T17:11:47.865982Z","end":"2026-03-13T17:11:48.048739Z","steps":["trace[1626259257] 'range keys from in-memory index tree' (duration: 182.421796ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:11:48.176149Z","caller":"traceutil/trace.go:171","msg":"trace[1372594599] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"122.557282ms","start":"2026-03-13T17:11:48.053558Z","end":"2026-03-13T17:11:48.176116Z","steps":["trace[1372594599] 'process raft request' (duration: 122.381878ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:11:48.17778Z","caller":"traceutil/trace.go:171","msg":"trace[653555661] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"124.011653ms","start":"2026-03-13T17:11:48.053749Z","end":"2026-03-13T17:11:48.17776Z","steps":["trace[653555661] 'process raft request' (duration: 123.632265ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:11:54.05389Z","caller":"traceutil/trace.go:171","msg":"trace[1308032514] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"760.550269ms","start":"2026-03-13T17:11:53.293312Z","end":"2026-03-13T17:11:54.053862Z","steps":["trace[1308032514] 'process raft request' (duration: 749.622735ms)","trace[1308032514] 'compare' (duration: 10.826922ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:11:54.055253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:11:53.293287Z","time spent":"760.700183ms","remote":"127.0.0.1:54538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:11:54.714158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629782335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:11:55.215379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629782335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:11:55.716592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629782335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:11:56.216974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629782335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:11:56.274286Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.117816325s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T17:11:56.27497Z","caller":"traceutil/trace.go:171","msg":"trace[156558957] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"2.208980207s","start":"2026-03-13T17:11:54.065959Z","end":"2026-03-13T17:11:56.274939Z","steps":["trace[156558957] 'process raft request' (duration: 2.208773603s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:11:56.275168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:11:54.065941Z","time spent":"2.2091282s","remote":"127.0.0.1:54538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:11:56.427714Z","caller":"traceutil/trace.go:171","msg":"trace[318969701] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"907.085365ms","start":"2026-03-13T17:11:55.520596Z","end":"2026-03-13T17:11:56.427681Z","steps":["trace[318969701] 'process raft request' (duration: 833.232699ms)","trace[318969701] 'compare' (duration: 73.656431ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:11:56.427943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:11:55.520568Z","time spent":"907.219877ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:11:56.428175Z","caller":"traceutil/trace.go:171","msg":"trace[1063073879] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"2.214697114s","start":"2026-03-13T17:11:54.213437Z","end":"2026-03-13T17:11:56.428135Z","steps":["trace[1063073879] 'read index received' (duration: 2.061328887s)","trace[1063073879] 'applied index is now lower than readState.Index' (duration: 153.362896ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:11:56.428341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.214911048s","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-03-13T17:11:56.428422Z","caller":"traceutil/trace.go:171","msg":"trace[1072521155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:352; }","duration":"2.215068292s","start":"2026-03-13T17:11:54.213339Z","end":"2026-03-13T17:11:56.428408Z","steps":["trace[1072521155] 'agreement among raft nodes before linearized reading' (duration: 2.214952999s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:11:56.428639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:11:54.213315Z","time spent":"2.215311237s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T17:11:56.428746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430441986s","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-03-13T17:11:56.42881Z","caller":"traceutil/trace.go:171","msg":"trace[1327226506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:353; }","duration":"1.4305693s","start":"2026-03-13T17:11:54.998227Z","end":"2026-03-13T17:11:56.428796Z","steps":["trace[1327226506] 'agreement among raft nodes before linearized reading' (duration: 1.430336424s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:11:56.428854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:11:54.998212Z","time spent":"1.430633791s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T17:11:56.429066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324166469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-13T17:11:56.429193Z","caller":"traceutil/trace.go:171","msg":"trace[1774504651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:353; }","duration":"1.324395404s","start":"2026-03-13T17:11:55.104747Z","end":"2026-03-13T17:11:56.429143Z","steps":["trace[1774504651] 'agreement among raft nodes before linearized reading' (duration: 1.324104187s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:11:56.429113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12765178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-13T17:11:56.429299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:11:55.104709Z","time spent":"1.324525577s","remote":"127.0.0.1:54640","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-03-13T17:11:56.429311Z","caller":"traceutil/trace.go:171","msg":"trace[618345277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"1.127942717s","start":"2026-03-13T17:11:55.301332Z","end":"2026-03-13T17:11:56.429274Z","steps":["trace[618345277] 'agreement among raft nodes before linearized reading' (duration: 1.127483106s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:11:56.429432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:11:55.301298Z","time spent":"1.12812024s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T17:11:56.428674Z","caller":"traceutil/trace.go:171","msg":"trace[640962990] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"144.092791ms","start":"2026-03-13T17:11:56.284556Z","end":"2026-03-13T17:11:56.428649Z","steps":["trace[640962990] 'process raft request' (duration: 143.875336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:18.501989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629782632,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T17:12:18.561565Z","caller":"traceutil/trace.go:171","msg":"trace[440142577] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"685.495845ms","start":"2026-03-13T17:12:17.876042Z","end":"2026-03-13T17:12:18.561538Z","steps":["trace[440142577] 'process raft request' (duration: 685.25749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:18.561738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:17.876021Z","time spent":"685.63352ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T17:12:18.575049Z","caller":"traceutil/trace.go:171","msg":"trace[441178458] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:522; }","duration":"573.845609ms","start":"2026-03-13T17:12:18.001131Z","end":"2026-03-13T17:12:18.574977Z","steps":["trace[441178458] 'read index received' (duration: 560.180754ms)","trace[441178458] 'applied index is now lower than readState.Index' (duration: 13.663485ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:18.575314Z","caller":"traceutil/trace.go:171","msg":"trace[1821437589] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"518.711529ms","start":"2026-03-13T17:12:18.056563Z","end":"2026-03-13T17:12:18.575275Z","steps":["trace[1821437589] 'process raft request' (duration: 518.203308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:18.575461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:18.056543Z","time spent":"518.846892ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T17:12:18.575509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.350999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:12:18.575612Z","caller":"traceutil/trace.go:171","msg":"trace[24301922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"574.510373ms","start":"2026-03-13T17:12:18.001078Z","end":"2026-03-13T17:12:18.575588Z","steps":["trace[24301922] 'agreement among raft nodes before linearized reading' (duration: 574.273298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:18.575709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:18.001058Z","time spent":"574.641006ms","remote":"127.0.0.1:54362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T17:12:18.582464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.629659ms","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-03-13T17:12:18.582511Z","caller":"traceutil/trace.go:171","msg":"trace[1740777814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:506; }","duration":"103.717382ms","start":"2026-03-13T17:12:18.478783Z","end":"2026-03-13T17:12:18.5825Z","steps":["trace[1740777814] 'agreement among raft nodes before linearized reading' (duration: 103.638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:18.582642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.210816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-03-13T17:12:18.582668Z","caller":"traceutil/trace.go:171","msg":"trace[1817459968] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:506; }","duration":"127.281337ms","start":"2026-03-13T17:12:18.455378Z","end":"2026-03-13T17:12:18.582659Z","steps":["trace[1817459968] 'agreement among raft nodes before linearized reading' (duration: 127.213696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:18.582789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.55209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-13T17:12:18.58281Z","caller":"traceutil/trace.go:171","msg":"trace[1144583082] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:506; }","duration":"327.57517ms","start":"2026-03-13T17:12:18.25523Z","end":"2026-03-13T17:12:18.582806Z","steps":["trace[1144583082] 'agreement among raft nodes before linearized reading' (duration: 327.517919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:18.582828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:18.255103Z","time spent":"327.720364ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-03-13T17:12:22.813156Z","caller":"traceutil/trace.go:171","msg":"trace[1773450870] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"183.346176ms","start":"2026-03-13T17:12:22.629784Z","end":"2026-03-13T17:12:22.81313Z","steps":["trace[1773450870] 'process raft request' (duration: 183.110201ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:22.814667Z","caller":"traceutil/trace.go:171","msg":"trace[1868789138] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"184.747127ms","start":"2026-03-13T17:12:22.629882Z","end":"2026-03-13T17:12:22.814629Z","steps":["trace[1868789138] 'process raft request' (duration: 184.467491ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:22.815156Z","caller":"traceutil/trace.go:171","msg":"trace[866496098] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"185.251159ms","start":"2026-03-13T17:12:22.629868Z","end":"2026-03-13T17:12:22.815119Z","steps":["trace[866496098] 'process raft request' (duration: 184.688586ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:22.815527Z","caller":"traceutil/trace.go:171","msg":"trace[904031091] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"185.440092ms","start":"2026-03-13T17:12:22.630064Z","end":"2026-03-13T17:12:22.815504Z","steps":["trace[904031091] 'process raft request' (duration: 184.87637ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:22.815623Z","caller":"traceutil/trace.go:171","msg":"trace[501548302] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"185.426352ms","start":"2026-03-13T17:12:22.630162Z","end":"2026-03-13T17:12:22.815588Z","steps":["trace[501548302] 'process raft request' (duration: 185.252688ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:26.213717Z","caller":"traceutil/trace.go:171","msg":"trace[2022574092] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"214.688315ms","start":"2026-03-13T17:12:25.998998Z","end":"2026-03-13T17:12:26.213686Z","steps":["trace[2022574092] 'read index received' (duration: 214.46279ms)","trace[2022574092] 'applied index is now lower than readState.Index' (duration: 224.285µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:12:26.213893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.869419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:12:26.213938Z","caller":"traceutil/trace.go:171","msg":"trace[878654348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"214.959401ms","start":"2026-03-13T17:12:25.998964Z","end":"2026-03-13T17:12:26.213924Z","steps":["trace[878654348] 'agreement among raft nodes before linearized reading' (duration: 214.843028ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:26.214291Z","caller":"traceutil/trace.go:171","msg":"trace[1016950467] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"838.784202ms","start":"2026-03-13T17:12:25.375491Z","end":"2026-03-13T17:12:26.214275Z","steps":["trace[1016950467] 'process raft request' (duration: 837.992924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:26.214369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:25.375472Z","time spent":"838.852713ms","remote":"127.0.0.1:54538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:27.132002Z","caller":"traceutil/trace.go:171","msg":"trace[1119774231] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"269.666581ms","start":"2026-03-13T17:12:26.862302Z","end":"2026-03-13T17:12:27.131969Z","steps":["trace[1119774231] 'process raft request' (duration: 243.045737ms)","trace[1119774231] 'compare' (duration: 26.337416ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:27.170283Z","caller":"traceutil/trace.go:171","msg":"trace[1097047374] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"305.60543ms","start":"2026-03-13T17:12:26.864654Z","end":"2026-03-13T17:12:27.170259Z","steps":["trace[1097047374] 'process raft request' (duration: 305.234512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:27.170541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:26.864639Z","time spent":"305.716163ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:27.170697Z","caller":"traceutil/trace.go:171","msg":"trace[570779222] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"306.003599ms","start":"2026-03-13T17:12:26.864675Z","end":"2026-03-13T17:12:27.170678Z","steps":["trace[570779222] 'process raft request' (duration: 305.528299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:27.17083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:26.86465Z","time spent":"306.118971ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:27.170943Z","caller":"traceutil/trace.go:171","msg":"trace[1596991313] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"306.231314ms","start":"2026-03-13T17:12:26.864704Z","end":"2026-03-13T17:12:27.170936Z","steps":["trace[1596991313] 'process raft request' (duration: 305.851766ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:27.170965Z","caller":"traceutil/trace.go:171","msg":"trace[431761475] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:569; }","duration":"171.648395ms","start":"2026-03-13T17:12:26.999297Z","end":"2026-03-13T17:12:27.170946Z","steps":["trace[431761475] 'read index received' (duration: 106.044864ms)","trace[431761475] 'applied index is now lower than readState.Index' (duration: 65.602541ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:12:27.171004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:26.864683Z","time spent":"306.299847ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:27.171074Z","caller":"traceutil/trace.go:171","msg":"trace[37038830] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"306.337748ms","start":"2026-03-13T17:12:26.864725Z","end":"2026-03-13T17:12:27.171062Z","steps":["trace[37038830] 'process raft request' (duration: 306.066111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:27.171142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:26.86472Z","time spent":"306.382399ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:27.171354Z","caller":"traceutil/trace.go:171","msg":"trace[249782246] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"305.741054ms","start":"2026-03-13T17:12:26.865587Z","end":"2026-03-13T17:12:27.171328Z","steps":["trace[249782246] 'process raft request' (duration: 305.262343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:27.171365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.027604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T17:12:27.171449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:26.865577Z","time spent":"305.826926ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:27.171456Z","caller":"traceutil/trace.go:171","msg":"trace[1440370339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"172.174988ms","start":"2026-03-13T17:12:26.999263Z","end":"2026-03-13T17:12:27.171437Z","steps":["trace[1440370339] 'agreement among raft nodes before linearized reading' (duration: 171.936113ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:27.171405Z","caller":"traceutil/trace.go:171","msg":"trace[18461621] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"241.502473ms","start":"2026-03-13T17:12:26.929883Z","end":"2026-03-13T17:12:27.171386Z","steps":["trace[18461621] 'process raft request' (duration: 241.024761ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:39.102605Z","caller":"traceutil/trace.go:171","msg":"trace[1124864353] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"308.70653ms","start":"2026-03-13T17:12:38.793867Z","end":"2026-03-13T17:12:39.102573Z","steps":["trace[1124864353] 'process raft request' (duration: 308.473545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:39.10275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:38.793835Z","time spent":"308.845553ms","remote":"127.0.0.1:54538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:45.660431Z","caller":"traceutil/trace.go:171","msg":"trace[1420420712] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"389.847438ms","start":"2026-03-13T17:12:45.270559Z","end":"2026-03-13T17:12:45.660407Z","steps":["trace[1420420712] 'read index received' (duration: 389.609543ms)","trace[1420420712] 'applied index is now lower than readState.Index' (duration: 236.755µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:45.660483Z","caller":"traceutil/trace.go:171","msg":"trace[2044239969] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"394.746427ms","start":"2026-03-13T17:12:45.265705Z","end":"2026-03-13T17:12:45.660451Z","steps":["trace[2044239969] 'process raft request' (duration: 394.567783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:45.660606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.265687Z","time spent":"394.833369ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:12:45.66064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.076564ms","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-03-13T17:12:45.660675Z","caller":"traceutil/trace.go:171","msg":"trace[1622210485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:696; }","duration":"390.137185ms","start":"2026-03-13T17:12:45.270527Z","end":"2026-03-13T17:12:45.660664Z","steps":["trace[1622210485] 'agreement among raft nodes before linearized reading' (duration: 390.010832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:45.660708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.270513Z","time spent":"390.185615ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T17:12:45.660783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.069635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-13T17:12:45.660838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.93108ms","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-03-13T17:12:45.660886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.045024ms","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-03-13T17:12:45.660887Z","caller":"traceutil/trace.go:171","msg":"trace[1395669904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:696; }","duration":"387.189568ms","start":"2026-03-13T17:12:45.273669Z","end":"2026-03-13T17:12:45.660859Z","steps":["trace[1395669904] 'agreement among raft nodes before linearized reading' (duration: 386.921252ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:45.660918Z","caller":"traceutil/trace.go:171","msg":"trace[2144668474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:696; }","duration":"382.107516ms","start":"2026-03-13T17:12:45.278801Z","end":"2026-03-13T17:12:45.660909Z","steps":["trace[2144668474] 'agreement among raft nodes before linearized reading' (duration: 382.025134ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:45.660912Z","caller":"traceutil/trace.go:171","msg":"trace[1366341291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:696; }","duration":"373.037933ms","start":"2026-03-13T17:12:45.287854Z","end":"2026-03-13T17:12:45.660892Z","steps":["trace[1366341291] 'agreement among raft nodes before linearized reading' (duration: 372.838088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:45.660949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.278786Z","time spent":"382.156557ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T17:12:45.660947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.273059Z","time spent":"387.874405ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T17:12:45.660959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.287836Z","time spent":"373.114235ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-13T17:12:46.481224Z","caller":"traceutil/trace.go:171","msg":"trace[175168930] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"817.146653ms","start":"2026-03-13T17:12:45.664058Z","end":"2026-03-13T17:12:46.481205Z","steps":["trace[175168930] 'process raft request' (duration: 817.01273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:46.481347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.664005Z","time spent":"817.281066ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:46.524008Z","caller":"traceutil/trace.go:171","msg":"trace[1590593336] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:722; }","duration":"524.883935ms","start":"2026-03-13T17:12:45.999108Z","end":"2026-03-13T17:12:46.523992Z","steps":["trace[1590593336] 'read index received' (duration: 482.000544ms)","trace[1590593336] 'applied index is now lower than readState.Index' (duration: 42.882511ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:46.524258Z","caller":"traceutil/trace.go:171","msg":"trace[117411993] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"858.759725ms","start":"2026-03-13T17:12:45.66549Z","end":"2026-03-13T17:12:46.524249Z","steps":["trace[117411993] 'process raft request' (duration: 858.340766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:46.524345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.665466Z","time spent":"858.820997ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:46.524418Z","caller":"traceutil/trace.go:171","msg":"trace[1541224324] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"858.371806ms","start":"2026-03-13T17:12:45.666007Z","end":"2026-03-13T17:12:46.524379Z","steps":["trace[1541224324] 'process raft request' (duration: 857.934867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:46.524582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.665996Z","time spent":"858.499928ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:46.524748Z","caller":"traceutil/trace.go:171","msg":"trace[1654786946] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"858.374586ms","start":"2026-03-13T17:12:45.666343Z","end":"2026-03-13T17:12:46.524717Z","steps":["trace[1654786946] 'process raft request' (duration: 857.62357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:46.524472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.342294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T17:12:46.524892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.666329Z","time spent":"858.498918ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:46.524956Z","caller":"traceutil/trace.go:171","msg":"trace[139632786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"525.885646ms","start":"2026-03-13T17:12:45.999057Z","end":"2026-03-13T17:12:46.524943Z","steps":["trace[139632786] 'agreement among raft nodes before linearized reading' (duration: 525.286743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:46.525137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:45.999031Z","time spent":"526.09818ms","remote":"127.0.0.1:54362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T17:12:47.057779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.960289ms","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":"info","ts":"2026-03-13T17:12:47.057872Z","caller":"traceutil/trace.go:171","msg":"trace[30979125] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:700; }","duration":"410.086341ms","start":"2026-03-13T17:12:46.647762Z","end":"2026-03-13T17:12:47.057848Z","steps":["trace[30979125] 'count revisions from in-memory index tree' (duration: 409.907268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:47.057916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:46.647751Z","time spent":"410.154183ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-13T17:12:47.0581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.127982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-13T17:12:47.058154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.459706ms","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-03-13T17:12:47.058187Z","caller":"traceutil/trace.go:171","msg":"trace[1846663700] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:700; }","duration":"423.509158ms","start":"2026-03-13T17:12:46.634667Z","end":"2026-03-13T17:12:47.058176Z","steps":["trace[1846663700] 'count revisions from in-memory index tree' (duration: 423.413096ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:47.058152Z","caller":"traceutil/trace.go:171","msg":"trace[2099595435] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:700; }","duration":"449.216433ms","start":"2026-03-13T17:12:46.608923Z","end":"2026-03-13T17:12:47.05814Z","steps":["trace[2099595435] 'range keys from in-memory index tree' (duration: 448.934667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:47.058216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:46.634659Z","time spent":"423.549049ms","remote":"127.0.0.1:54504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-13T17:12:47.058226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:46.608904Z","time spent":"449.313846ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"warn","ts":"2026-03-13T17:12:48.698269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.204503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:12:48.698495Z","caller":"traceutil/trace.go:171","msg":"trace[1482066699] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"165.464344ms","start":"2026-03-13T17:12:48.533014Z","end":"2026-03-13T17:12:48.698478Z","steps":["trace[1482066699] 'process raft request' (duration: 165.424234ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:48.69867Z","caller":"traceutil/trace.go:171","msg":"trace[1012904572] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"167.487638ms","start":"2026-03-13T17:12:48.531169Z","end":"2026-03-13T17:12:48.698656Z","steps":["trace[1012904572] 'process raft request' (duration: 46.57702ms)","trace[1012904572] 'compare' (duration: 120.004179ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:48.698794Z","caller":"traceutil/trace.go:171","msg":"trace[1180235979] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"167.361735ms","start":"2026-03-13T17:12:48.531407Z","end":"2026-03-13T17:12:48.698768Z","steps":["trace[1180235979] 'process raft request' (duration: 166.970787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:50.171918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629783045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:12:50.672056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629783045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:12:50.682588Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.228962795s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T17:12:50.683287Z","caller":"traceutil/trace.go:171","msg":"trace[378909066] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"1.229742892s","start":"2026-03-13T17:12:49.453524Z","end":"2026-03-13T17:12:50.683267Z","steps":["trace[378909066] 'process raft request' (duration: 1.229548058s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:50.683406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:49.453502Z","time spent":"1.229838124s","remote":"127.0.0.1:54534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5169,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:50.749834Z","caller":"traceutil/trace.go:171","msg":"trace[1567299690] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:734; }","duration":"1.078668682s","start":"2026-03-13T17:12:49.671144Z","end":"2026-03-13T17:12:50.749813Z","steps":["trace[1567299690] 'read index received' (duration: 1.011957966s)","trace[1567299690] 'applied index is now lower than readState.Index' (duration: 66.709266ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:50.749966Z","caller":"traceutil/trace.go:171","msg":"trace[1369181004] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"895.593803ms","start":"2026-03-13T17:12:49.854363Z","end":"2026-03-13T17:12:50.749957Z","steps":["trace[1369181004] 'process raft request' (duration: 895.360658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:50.750065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:49.85434Z","time spent":"895.651244ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:12:50.750069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:49.465324Z","time spent":"1.284738729s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-13T17:12:50.750325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079194845s","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-03-13T17:12:50.750362Z","caller":"traceutil/trace.go:171","msg":"trace[1522305001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"1.079294656s","start":"2026-03-13T17:12:49.671059Z","end":"2026-03-13T17:12:50.750354Z","steps":["trace[1522305001] 'agreement among raft nodes before linearized reading' (duration: 1.079189464s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:50.750388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:49.671039Z","time spent":"1.079343778s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T17:12:50.750475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.638145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:12:50.750538Z","caller":"traceutil/trace.go:171","msg":"trace[1852380684] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:709; }","duration":"412.744858ms","start":"2026-03-13T17:12:50.337775Z","end":"2026-03-13T17:12:50.75052Z","steps":["trace[1852380684] 'agreement among raft nodes before linearized reading' (duration: 412.645136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:50.750583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:50.337757Z","time spent":"412.817069ms","remote":"127.0.0.1:54804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-13T17:12:50.750861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.565903ms","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-03-13T17:12:50.750893Z","caller":"traceutil/trace.go:171","msg":"trace[1219032245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:709; }","duration":"258.637795ms","start":"2026-03-13T17:12:50.492248Z","end":"2026-03-13T17:12:50.750886Z","steps":["trace[1219032245] 'agreement among raft nodes before linearized reading' (duration: 258.570533ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:57.008064Z","caller":"traceutil/trace.go:171","msg":"trace[703044175] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"116.444653ms","start":"2026-03-13T17:12:56.891601Z","end":"2026-03-13T17:12:57.008045Z","steps":["trace[703044175] 'process raft request' (duration: 46.046468ms)","trace[703044175] 'compare' (duration: 69.993546ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:57.008187Z","caller":"traceutil/trace.go:171","msg":"trace[1932627289] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"116.32592ms","start":"2026-03-13T17:12:56.891839Z","end":"2026-03-13T17:12:57.008165Z","steps":["trace[1932627289] 'process raft request' (duration: 115.966463ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:11.086374Z","caller":"traceutil/trace.go:171","msg":"trace[122655750] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"141.117187ms","start":"2026-03-13T17:13:10.945231Z","end":"2026-03-13T17:13:11.086348Z","steps":["trace[122655750] 'process raft request' (duration: 122.575443ms)","trace[122655750] 'compare' (duration: 18.190647ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:12.622788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.640798ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-13T17:13:12.622884Z","caller":"traceutil/trace.go:171","msg":"trace[1618357888] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"556.244435ms","start":"2026-03-13T17:13:12.066623Z","end":"2026-03-13T17:13:12.622868Z","steps":["trace[1618357888] 'read index received' (duration: 44.442114ms)","trace[1618357888] 'applied index is now lower than readState.Index' (duration: 511.801051ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:12.622954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.330577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:12.622987Z","caller":"traceutil/trace.go:171","msg":"trace[975401238] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:776; }","duration":"556.401779ms","start":"2026-03-13T17:13:12.066573Z","end":"2026-03-13T17:13:12.622974Z","steps":["trace[975401238] 'agreement among raft nodes before linearized reading' (duration: 556.339548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:12.622904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.893156ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T17:13:12.62303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:12.066548Z","time spent":"556.46718ms","remote":"127.0.0.1:54440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-03-13T17:13:12.623058Z","caller":"traceutil/trace.go:171","msg":"trace[388185310] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:776; }","duration":"184.065549ms","start":"2026-03-13T17:13:12.43897Z","end":"2026-03-13T17:13:12.623035Z","steps":["trace[388185310] 'range keys from in-memory index tree' (duration: 183.856655ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:13.211291Z","caller":"traceutil/trace.go:171","msg":"trace[1676315668] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"115.894661ms","start":"2026-03-13T17:13:13.09537Z","end":"2026-03-13T17:13:13.211265Z","steps":["trace[1676315668] 'process raft request' (duration: 114.40768ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:13.211548Z","caller":"traceutil/trace.go:171","msg":"trace[1823600992] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"116.080047ms","start":"2026-03-13T17:13:13.095444Z","end":"2026-03-13T17:13:13.211524Z","steps":["trace[1823600992] 'process raft request' (duration: 115.765959ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:25.413478Z","caller":"traceutil/trace.go:171","msg":"trace[655963211] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"126.432386ms","start":"2026-03-13T17:13:25.287019Z","end":"2026-03-13T17:13:25.413451Z","steps":["trace[655963211] 'read index received' (duration: 126.252752ms)","trace[655963211] 'applied index is now lower than readState.Index' (duration: 178.714µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:25.413668Z","caller":"traceutil/trace.go:171","msg":"trace[378360705] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"262.233979ms","start":"2026-03-13T17:13:25.151371Z","end":"2026-03-13T17:13:25.413605Z","steps":["trace[378360705] 'process raft request' (duration: 261.82625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:25.413926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.858321ms","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-03-13T17:13:25.414017Z","caller":"traceutil/trace.go:171","msg":"trace[1020561115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:863; }","duration":"123.990533ms","start":"2026-03-13T17:13:25.290006Z","end":"2026-03-13T17:13:25.413996Z","steps":["trace[1020561115] 'agreement among raft nodes before linearized reading' (duration: 123.745828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:25.414022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.994597ms","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-03-13T17:13:25.413916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.021554ms","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-03-13T17:13:25.414121Z","caller":"traceutil/trace.go:171","msg":"trace[1562276375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:863; }","duration":"124.276889ms","start":"2026-03-13T17:13:25.289821Z","end":"2026-03-13T17:13:25.414098Z","steps":["trace[1562276375] 'agreement among raft nodes before linearized reading' (duration: 123.915252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:25.413934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.912591ms","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-03-13T17:13:25.414179Z","caller":"traceutil/trace.go:171","msg":"trace[1570171025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:863; }","duration":"124.199058ms","start":"2026-03-13T17:13:25.289972Z","end":"2026-03-13T17:13:25.414171Z","steps":["trace[1570171025] 'agreement among raft nodes before linearized reading' (duration: 123.80693ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:25.414067Z","caller":"traceutil/trace.go:171","msg":"trace[1282024610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:863; }","duration":"127.107609ms","start":"2026-03-13T17:13:25.286947Z","end":"2026-03-13T17:13:25.414055Z","steps":["trace[1282024610] 'agreement among raft nodes before linearized reading' (duration: 126.833414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:27.505851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629783434,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T17:13:27.532816Z","caller":"traceutil/trace.go:171","msg":"trace[1422800005] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"527.023724ms","start":"2026-03-13T17:13:27.00577Z","end":"2026-03-13T17:13:27.532794Z","steps":["trace[1422800005] 'read index received' (duration: 526.963803ms)","trace[1422800005] 'applied index is now lower than readState.Index' (duration: 58.841µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:27.532916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:26.625132Z","time spent":"907.781333ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-13T17:13:27.533151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.444314ms","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-03-13T17:13:27.533178Z","caller":"traceutil/trace.go:171","msg":"trace[477588267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:867; }","duration":"108.497365ms","start":"2026-03-13T17:13:27.424675Z","end":"2026-03-13T17:13:27.533172Z","steps":["trace[477588267] 'agreement among raft nodes before linearized reading' (duration: 108.373833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:27.533157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.378547ms","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-03-13T17:13:27.533214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.314983ms","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-03-13T17:13:27.533208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.063487ms","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-03-13T17:13:27.533244Z","caller":"traceutil/trace.go:171","msg":"trace[1624197104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:867; }","duration":"112.50574ms","start":"2026-03-13T17:13:27.420717Z","end":"2026-03-13T17:13:27.533223Z","steps":["trace[1624197104] 'agreement among raft nodes before linearized reading' (duration: 112.328127ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:27.533259Z","caller":"traceutil/trace.go:171","msg":"trace[876412314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:867; }","duration":"109.425335ms","start":"2026-03-13T17:13:27.423821Z","end":"2026-03-13T17:13:27.533247Z","steps":["trace[876412314] 'agreement among raft nodes before linearized reading' (duration: 109.292532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:27.533258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.493675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:27.533276Z","caller":"traceutil/trace.go:171","msg":"trace[1612888899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:867; }","duration":"109.18838ms","start":"2026-03-13T17:13:27.42407Z","end":"2026-03-13T17:13:27.533258Z","steps":["trace[1612888899] 'agreement among raft nodes before linearized reading' (duration: 108.977746ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:27.533308Z","caller":"traceutil/trace.go:171","msg":"trace[448867630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:867; }","duration":"527.568206ms","start":"2026-03-13T17:13:27.005725Z","end":"2026-03-13T17:13:27.533293Z","steps":["trace[448867630] 'agreement among raft nodes before linearized reading' (duration: 527.485844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:27.53335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:27.005711Z","time spent":"527.626378ms","remote":"127.0.0.1:54362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T17:13:27.533429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.692589ms","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-03-13T17:13:27.533458Z","caller":"traceutil/trace.go:171","msg":"trace[2106580267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:867; }","duration":"108.751711ms","start":"2026-03-13T17:13:27.424697Z","end":"2026-03-13T17:13:27.533449Z","steps":["trace[2106580267] 'agreement among raft nodes before linearized reading' (duration: 108.645569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:28.02695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.561389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:13:28.027053Z","caller":"traceutil/trace.go:171","msg":"trace[404566429] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"491.930989ms","start":"2026-03-13T17:13:27.535103Z","end":"2026-03-13T17:13:28.027034Z","steps":["trace[404566429] 'process raft request' (duration: 276.130556ms)","trace[404566429] 'compare' (duration: 215.305563ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:28.027119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:27.53509Z","time spent":"491.99377ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:28.211709Z","caller":"traceutil/trace.go:171","msg":"trace[583440099] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:906; }","duration":"182.678471ms","start":"2026-03-13T17:13:28.02901Z","end":"2026-03-13T17:13:28.211689Z","steps":["trace[583440099] 'read index received' (duration: 181.735601ms)","trace[583440099] 'applied index is now lower than readState.Index' (duration: 942.08µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:28.211956Z","caller":"traceutil/trace.go:171","msg":"trace[1158539839] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"675.448537ms","start":"2026-03-13T17:13:27.536496Z","end":"2026-03-13T17:13:28.211945Z","steps":["trace[1158539839] 'process raft request' (duration: 674.243632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:28.212082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:27.536477Z","time spent":"675.517569ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:28.2122Z","caller":"traceutil/trace.go:171","msg":"trace[591983743] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"673.453605ms","start":"2026-03-13T17:13:27.538717Z","end":"2026-03-13T17:13:28.212171Z","steps":["trace[591983743] 'process raft request' (duration: 672.939114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:28.212283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.269613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-13T17:13:28.212327Z","caller":"traceutil/trace.go:171","msg":"trace[1994348300] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:873; }","duration":"183.342304ms","start":"2026-03-13T17:13:28.028973Z","end":"2026-03-13T17:13:28.212316Z","steps":["trace[1994348300] 'agreement among raft nodes before linearized reading' (duration: 183.271142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:28.212405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:27.53871Z","time spent":"673.571057ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:28.212722Z","caller":"traceutil/trace.go:171","msg":"trace[1946137472] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"676.04091ms","start":"2026-03-13T17:13:27.536666Z","end":"2026-03-13T17:13:28.212707Z","steps":["trace[1946137472] 'process raft request' (duration: 674.876586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:28.212794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:27.536656Z","time spent":"676.104951ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:28.212952Z","caller":"traceutil/trace.go:171","msg":"trace[1026273419] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"675.98126ms","start":"2026-03-13T17:13:27.536959Z","end":"2026-03-13T17:13:28.212941Z","steps":["trace[1026273419] 'process raft request' (duration: 674.635491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:28.213021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:27.53694Z","time spent":"676.048551ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:28.213068Z","caller":"traceutil/trace.go:171","msg":"trace[1388301151] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"674.490097ms","start":"2026-03-13T17:13:27.538566Z","end":"2026-03-13T17:13:28.213056Z","steps":["trace[1388301151] 'process raft request' (duration: 673.058326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:28.213126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:27.538555Z","time spent":"674.539949ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:44.714027Z","caller":"traceutil/trace.go:171","msg":"trace[264041260] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"406.300459ms","start":"2026-03-13T17:13:44.307707Z","end":"2026-03-13T17:13:44.714008Z","steps":["trace[264041260] 'read index received' (duration: 406.207327ms)","trace[264041260] 'applied index is now lower than readState.Index' (duration: 92.082µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:44.714301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.574625ms","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-03-13T17:13:44.714381Z","caller":"traceutil/trace.go:171","msg":"trace[256040876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"406.700978ms","start":"2026-03-13T17:13:44.307663Z","end":"2026-03-13T17:13:44.714364Z","steps":["trace[256040876] 'agreement among raft nodes before linearized reading' (duration: 406.457362ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.714426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.307645Z","time spent":"406.771859ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T17:13:44.714389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.752349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-13T17:13:44.714513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.931337ms","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-03-13T17:13:44.714629Z","caller":"traceutil/trace.go:171","msg":"trace[2036646144] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"418.002049ms","start":"2026-03-13T17:13:44.296094Z","end":"2026-03-13T17:13:44.714097Z","steps":["trace[2036646144] 'process raft request' (duration: 417.766624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.714788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.296083Z","time spent":"418.631322ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:44.714661Z","caller":"traceutil/trace.go:171","msg":"trace[147171890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"400.271865ms","start":"2026-03-13T17:13:44.314371Z","end":"2026-03-13T17:13:44.714643Z","steps":["trace[147171890] 'agreement among raft nodes before linearized reading' (duration: 399.871046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.714952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.314342Z","time spent":"400.566741ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T17:13:44.714575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.142764ms","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-03-13T17:13:44.715066Z","caller":"traceutil/trace.go:171","msg":"trace[2012556579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:923; }","duration":"401.648895ms","start":"2026-03-13T17:13:44.3134Z","end":"2026-03-13T17:13:44.715049Z","steps":["trace[2012556579] 'agreement among raft nodes before linearized reading' (duration: 401.051481ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:44.714537Z","caller":"traceutil/trace.go:171","msg":"trace[1927401694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:923; }","duration":"402.923574ms","start":"2026-03-13T17:13:44.311602Z","end":"2026-03-13T17:13:44.714526Z","steps":["trace[1927401694] 'agreement among raft nodes before linearized reading' (duration: 402.602857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.715134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.31339Z","time spent":"401.725606ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T17:13:44.715191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.311575Z","time spent":"403.586488ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T17:13:45.61045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.225603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:13:45.610733Z","caller":"traceutil/trace.go:171","msg":"trace[1088251986] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"891.41219ms","start":"2026-03-13T17:13:44.719274Z","end":"2026-03-13T17:13:45.610686Z","steps":["trace[1088251986] 'process raft request' (duration: 392.829719ms)","trace[1088251986] 'compare' (duration: 497.975308ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:45.61078Z","caller":"traceutil/trace.go:171","msg":"trace[1778537492] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"891.459921ms","start":"2026-03-13T17:13:44.719302Z","end":"2026-03-13T17:13:45.610762Z","steps":["trace[1778537492] 'process raft request' (duration: 891.270477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:45.610898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.719248Z","time spent":"891.564624ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:13:45.610919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.719269Z","time spent":"891.565863ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:45.611042Z","caller":"traceutil/trace.go:171","msg":"trace[715782817] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"890.786036ms","start":"2026-03-13T17:13:44.720234Z","end":"2026-03-13T17:13:45.61102Z","steps":["trace[715782817] 'process raft request' (duration: 890.47162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:45.611181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.72022Z","time spent":"890.886408ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:45.611191Z","caller":"traceutil/trace.go:171","msg":"trace[2042328733] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:964; }","duration":"610.45149ms","start":"2026-03-13T17:13:45.000724Z","end":"2026-03-13T17:13:45.611176Z","steps":["trace[2042328733] 'read index received' (duration: 111.384068ms)","trace[2042328733] 'applied index is now lower than readState.Index' (duration: 499.065562ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:45.611341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.623813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:45.611377Z","caller":"traceutil/trace.go:171","msg":"trace[1728180388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"610.656034ms","start":"2026-03-13T17:13:45.000709Z","end":"2026-03-13T17:13:45.611365Z","steps":["trace[1728180388] 'agreement among raft nodes before linearized reading' (duration: 610.572922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:45.611362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.310238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T17:13:45.611411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:45.000649Z","time spent":"610.755687ms","remote":"127.0.0.1:54376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T17:13:45.611418Z","caller":"traceutil/trace.go:171","msg":"trace[1836567203] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:927; }","duration":"259.4224ms","start":"2026-03-13T17:13:45.351982Z","end":"2026-03-13T17:13:45.611405Z","steps":["trace[1836567203] 'agreement among raft nodes before linearized reading' (duration: 259.342598ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:45.611434Z","caller":"traceutil/trace.go:171","msg":"trace[1310010363] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"890.657394ms","start":"2026-03-13T17:13:44.720749Z","end":"2026-03-13T17:13:45.611406Z","steps":["trace[1310010363] 'process raft request' (duration: 890.145473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:45.611597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:44.720737Z","time spent":"890.771356ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:45.883011Z","caller":"traceutil/trace.go:171","msg":"trace[775496722] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"106.202403ms","start":"2026-03-13T17:13:45.776789Z","end":"2026-03-13T17:13:45.882991Z","steps":["trace[775496722] 'read index received' (duration: 69.489926ms)","trace[775496722] 'applied index is now lower than readState.Index' (duration: 36.711807ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:45.883139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.336895ms","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-03-13T17:13:45.883168Z","caller":"traceutil/trace.go:171","msg":"trace[490959875] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:928; }","duration":"106.417087ms","start":"2026-03-13T17:13:45.77674Z","end":"2026-03-13T17:13:45.883157Z","steps":["trace[490959875] 'agreement among raft nodes before linearized reading' (duration: 106.330526ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:45.883149Z","caller":"traceutil/trace.go:171","msg":"trace[1837018259] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"116.579243ms","start":"2026-03-13T17:13:45.766506Z","end":"2026-03-13T17:13:45.883085Z","steps":["trace[1837018259] 'process raft request' (duration: 79.770444ms)","trace[1837018259] 'compare' (duration: 36.587614ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:46.320527Z","caller":"traceutil/trace.go:171","msg":"trace[434725264] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"209.501581ms","start":"2026-03-13T17:13:46.110654Z","end":"2026-03-13T17:13:46.320155Z","steps":["trace[434725264] 'process raft request' (duration: 148.566775ms)","trace[434725264] 'compare' (duration: 60.820683ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:46.509249Z","caller":"traceutil/trace.go:171","msg":"trace[1532170991] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"119.58287ms","start":"2026-03-13T17:13:46.389643Z","end":"2026-03-13T17:13:46.509226Z","steps":["trace[1532170991] 'process raft request' (duration: 119.388576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:47.314348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.962778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-13T17:13:47.314405Z","caller":"traceutil/trace.go:171","msg":"trace[1615911921] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:932; }","duration":"690.057331ms","start":"2026-03-13T17:13:46.624335Z","end":"2026-03-13T17:13:47.314392Z","steps":["trace[1615911921] 'range keys from in-memory index tree' (duration: 689.797735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:47.314863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:46.624314Z","time spent":"690.539122ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"warn","ts":"2026-03-13T17:13:47.315144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.368826ms","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-03-13T17:13:47.315196Z","caller":"traceutil/trace.go:171","msg":"trace[839642499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"596.447538ms","start":"2026-03-13T17:13:46.718736Z","end":"2026-03-13T17:13:47.315183Z","steps":["trace[839642499] 'range keys from in-memory index tree' (duration: 596.251134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:47.319824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:46.718716Z","time spent":"601.083721ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T17:13:47.913718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.316473Z","time spent":"597.241467ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-13T17:13:48.052764Z","caller":"traceutil/trace.go:171","msg":"trace[1453772872] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:973; }","duration":"537.634361ms","start":"2026-03-13T17:13:47.515109Z","end":"2026-03-13T17:13:48.052744Z","steps":["trace[1453772872] 'read index received' (duration: 398.482285ms)","trace[1453772872] 'applied index is now lower than readState.Index' (duration: 139.151476ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:48.052885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.752593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:48.052867Z","caller":"traceutil/trace.go:171","msg":"trace[615454728] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"729.013687ms","start":"2026-03-13T17:13:47.323808Z","end":"2026-03-13T17:13:48.052821Z","steps":["trace[615454728] 'process raft request' (duration: 689.59601ms)","trace[615454728] 'compare' (duration: 39.231483ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:48.053032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.323779Z","time spent":"729.167261ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:48.052908Z","caller":"traceutil/trace.go:171","msg":"trace[1189151508] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:933; }","duration":"537.826664ms","start":"2026-03-13T17:13:47.515074Z","end":"2026-03-13T17:13:48.052901Z","steps":["trace[1189151508] 'agreement among raft nodes before linearized reading' (duration: 537.765293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.053207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.515057Z","time spent":"538.137271ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-03-13T17:13:48.314119Z","caller":"traceutil/trace.go:171","msg":"trace[2116843174] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"261.280773ms","start":"2026-03-13T17:13:48.052819Z","end":"2026-03-13T17:13:48.314099Z","steps":["trace[2116843174] 'read index received' (duration: 177.817326ms)","trace[2116843174] 'applied index is now lower than readState.Index' (duration: 83.462747ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:48.314153Z","caller":"traceutil/trace.go:171","msg":"trace[1311657335] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"399.436286ms","start":"2026-03-13T17:13:47.914688Z","end":"2026-03-13T17:13:48.314124Z","steps":["trace[1311657335] 'process raft request' (duration: 315.896847ms)","trace[1311657335] 'compare' (duration: 83.402116ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:48.314279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.914661Z","time spent":"399.548669ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:13:48.314299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.740326ms","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-03-13T17:13:48.314327Z","caller":"traceutil/trace.go:171","msg":"trace[1806228871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"699.817288ms","start":"2026-03-13T17:13:47.6145Z","end":"2026-03-13T17:13:48.314317Z","steps":["trace[1806228871] 'agreement among raft nodes before linearized reading' (duration: 699.679035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.314354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.61448Z","time spent":"699.866839ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T17:13:48.314336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.977995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T17:13:48.31437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.187506ms","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-03-13T17:13:48.314424Z","caller":"traceutil/trace.go:171","msg":"trace[688606022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"314.119588ms","start":"2026-03-13T17:13:48.000286Z","end":"2026-03-13T17:13:48.314405Z","steps":["trace[688606022] 'agreement among raft nodes before linearized reading' (duration: 313.950015ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:48.314449Z","caller":"traceutil/trace.go:171","msg":"trace[372285166] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:934; }","duration":"406.283319ms","start":"2026-03-13T17:13:47.908137Z","end":"2026-03-13T17:13:48.31442Z","steps":["trace[372285166] 'agreement among raft nodes before linearized reading' (duration: 406.203857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.314475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:48.000258Z","time spent":"314.20987ms","remote":"127.0.0.1:54362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T17:13:48.314501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.908117Z","time spent":"406.37191ms","remote":"127.0.0.1:55092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-03-13T17:13:48.314577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.579993ms","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-03-13T17:13:48.314291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.829345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T17:13:48.314606Z","caller":"traceutil/trace.go:171","msg":"trace[1988818789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"699.636014ms","start":"2026-03-13T17:13:47.614964Z","end":"2026-03-13T17:13:48.3146Z","steps":["trace[1988818789] 'agreement among raft nodes before linearized reading' (duration: 699.534102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.314622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.614951Z","time spent":"699.668245ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-13T17:13:48.314624Z","caller":"traceutil/trace.go:171","msg":"trace[26427672] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:934; }","duration":"529.220013ms","start":"2026-03-13T17:13:47.785396Z","end":"2026-03-13T17:13:48.314616Z","steps":["trace[26427672] 'agreement among raft nodes before linearized reading' (duration: 528.795044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.314652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.78538Z","time spent":"529.261804ms","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-03-13T17:13:48.314733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.642181ms","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-03-13T17:13:48.314755Z","caller":"traceutil/trace.go:171","msg":"trace[832951414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"698.705093ms","start":"2026-03-13T17:13:47.616044Z","end":"2026-03-13T17:13:48.314749Z","steps":["trace[832951414] 'agreement among raft nodes before linearized reading' (duration: 698.659672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.314772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.616022Z","time spent":"698.743864ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T17:13:48.314868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.863606ms","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-03-13T17:13:48.314887Z","caller":"traceutil/trace.go:171","msg":"trace[424227509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"698.908678ms","start":"2026-03-13T17:13:47.615975Z","end":"2026-03-13T17:13:48.314883Z","steps":["trace[424227509] 'agreement among raft nodes before linearized reading' (duration: 698.862967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.3149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:47.61596Z","time spent":"698.936878ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T17:13:48.737994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.177227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:13:48.738213Z","caller":"traceutil/trace.go:171","msg":"trace[979794364] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:976; }","duration":"418.724375ms","start":"2026-03-13T17:13:48.319472Z","end":"2026-03-13T17:13:48.738196Z","steps":["trace[979794364] 'read index received' (duration: 33.115827ms)","trace[979794364] 'applied index is now lower than readState.Index' (duration: 385.607558ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:48.738244Z","caller":"traceutil/trace.go:171","msg":"trace[2129011075] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"418.915109ms","start":"2026-03-13T17:13:48.31929Z","end":"2026-03-13T17:13:48.738205Z","steps":["trace[2129011075] 'process raft request' (duration: 118.446855ms)","trace[2129011075] 'compare' (duration: 299.961073ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:48.738284Z","caller":"traceutil/trace.go:171","msg":"trace[2084037478] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"418.687933ms","start":"2026-03-13T17:13:48.31955Z","end":"2026-03-13T17:13:48.738238Z","steps":["trace[2084037478] 'process raft request' (duration: 418.53968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.738361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.883028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-13T17:13:48.73839Z","caller":"traceutil/trace.go:171","msg":"trace[543383235] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:938; }","duration":"418.93337ms","start":"2026-03-13T17:13:48.319448Z","end":"2026-03-13T17:13:48.738381Z","steps":["trace[543383235] 'agreement among raft nodes before linearized reading' (duration: 418.798367ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:48.738352Z","caller":"traceutil/trace.go:171","msg":"trace[751107507] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"417.464877ms","start":"2026-03-13T17:13:48.320826Z","end":"2026-03-13T17:13:48.738291Z","steps":["trace[751107507] 'process raft request' (duration: 417.328944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.738425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:48.319442Z","time spent":"418.97368ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-03-13T17:13:48.738399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:48.319267Z","time spent":"419.043092ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:13:48.738518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:48.319535Z","time spent":"418.846768ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:13:48.738542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:48.320818Z","time spent":"417.62968ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:49.202754Z","caller":"traceutil/trace.go:171","msg":"trace[985894113] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"392.348078ms","start":"2026-03-13T17:13:48.810373Z","end":"2026-03-13T17:13:49.202721Z","steps":["trace[985894113] 'process raft request' (duration: 363.221761ms)","trace[985894113] 'compare' (duration: 28.985264ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:49.202875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:48.81035Z","time spent":"392.469371ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":762,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T17:13:49.901804Z","caller":"traceutil/trace.go:171","msg":"trace[445110561] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"824.146213ms","start":"2026-03-13T17:13:49.077641Z","end":"2026-03-13T17:13:49.901787Z","steps":["trace[445110561] 'read index received' (duration: 95.883903ms)","trace[445110561] 'applied index is now lower than readState.Index' (duration: 728.26159ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:49.902Z","caller":"traceutil/trace.go:171","msg":"trace[640312661] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"916.360066ms","start":"2026-03-13T17:13:48.985632Z","end":"2026-03-13T17:13:49.901992Z","steps":["trace[640312661] 'process raft request' (duration: 916.0689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:49.902072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:48.985596Z","time spent":"916.424827ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:13:49.902213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.583684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:49.902245Z","caller":"traceutil/trace.go:171","msg":"trace[255359252] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:940; }","duration":"824.667675ms","start":"2026-03-13T17:13:49.077567Z","end":"2026-03-13T17:13:49.902235Z","steps":["trace[255359252] 'agreement among raft nodes before linearized reading' (duration: 824.625004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:49.902267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:49.077537Z","time spent":"824.724067ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-13T17:13:49.902402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.975963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T17:13:49.902429Z","caller":"traceutil/trace.go:171","msg":"trace[1443094026] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:940; }","duration":"638.048554ms","start":"2026-03-13T17:13:49.264374Z","end":"2026-03-13T17:13:49.902422Z","steps":["trace[1443094026] 'agreement among raft nodes before linearized reading' (duration: 637.998173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:49.902448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:49.264354Z","time spent":"638.088575ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-13T17:13:50.611789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.922311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:13:50.611891Z","caller":"traceutil/trace.go:171","msg":"trace[853125738] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"612.231979ms","start":"2026-03-13T17:13:49.999644Z","end":"2026-03-13T17:13:50.611875Z","steps":["trace[853125738] 'read index received' (duration: 170.093923ms)","trace[853125738] 'applied index is now lower than readState.Index' (duration: 442.137066ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:50.611968Z","caller":"traceutil/trace.go:171","msg":"trace[647764788] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"692.520225ms","start":"2026-03-13T17:13:49.919437Z","end":"2026-03-13T17:13:50.611957Z","steps":["trace[647764788] 'process raft request' (duration: 250.371619ms)","trace[647764788] 'compare' (duration: 441.650395ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:50.612014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:49.919421Z","time spent":"692.571417ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":762,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T17:13:50.612314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.671339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:50.612346Z","caller":"traceutil/trace.go:171","msg":"trace[533877981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"612.73333ms","start":"2026-03-13T17:13:49.999603Z","end":"2026-03-13T17:13:50.612336Z","steps":["trace[533877981] 'agreement among raft nodes before linearized reading' (duration: 612.679999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:50.612373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:49.999587Z","time spent":"612.780971ms","remote":"127.0.0.1:54362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T17:13:50.61244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.1929ms","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-03-13T17:13:50.61268Z","caller":"traceutil/trace.go:171","msg":"trace[1133952790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"555.475287ms","start":"2026-03-13T17:13:50.057184Z","end":"2026-03-13T17:13:50.612659Z","steps":["trace[1133952790] 'agreement among raft nodes before linearized reading' (duration: 555.016776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:50.612738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:50.057163Z","time spent":"555.56272ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T17:13:50.613057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.442125ms","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-03-13T17:13:50.613112Z","caller":"traceutil/trace.go:171","msg":"trace[1202438804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"256.532427ms","start":"2026-03-13T17:13:50.356568Z","end":"2026-03-13T17:13:50.6131Z","steps":["trace[1202438804] 'agreement among raft nodes before linearized reading' (duration: 256.396004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:58.500801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629783745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:13:59.001417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537161629783745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:13:59.031932Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050922267s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T17:13:59.032349Z","caller":"traceutil/trace.go:171","msg":"trace[1038927467] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"1.03220841s","start":"2026-03-13T17:13:58.000119Z","end":"2026-03-13T17:13:59.032327Z","steps":["trace[1038927467] 'read index received' (duration: 1.031961825s)","trace[1038927467] 'applied index is now lower than readState.Index' (duration: 245.715µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:59.032445Z","caller":"traceutil/trace.go:171","msg":"trace[1920213334] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.051498969s","start":"2026-03-13T17:13:57.980934Z","end":"2026-03-13T17:13:59.032433Z","steps":["trace[1920213334] 'process raft request' (duration: 1.051228303s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.032548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:57.980921Z","time spent":"1.051551491s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:13:59.032603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.121584ms","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-03-13T17:13:59.033276Z","caller":"traceutil/trace.go:171","msg":"trace[1553683870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"332.843169ms","start":"2026-03-13T17:13:58.700416Z","end":"2026-03-13T17:13:59.033259Z","steps":["trace[1553683870] 'agreement among raft nodes before linearized reading' (duration: 332.039872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.033448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:58.700396Z","time spent":"333.042533ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T17:13:59.03277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.904622ms","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-03-13T17:13:59.033665Z","caller":"traceutil/trace.go:171","msg":"trace[1101418608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"236.801823ms","start":"2026-03-13T17:13:58.796837Z","end":"2026-03-13T17:13:59.033639Z","steps":["trace[1101418608] 'agreement among raft nodes before linearized reading' (duration: 235.859011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.032807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03269262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:59.033837Z","caller":"traceutil/trace.go:171","msg":"trace[752819093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"1.033759745s","start":"2026-03-13T17:13:58.000065Z","end":"2026-03-13T17:13:59.033825Z","steps":["trace[752819093] 'agreement among raft nodes before linearized reading' (duration: 1.032725571s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.033874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:58.000043Z","time spent":"1.033817607s","remote":"127.0.0.1:54362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T17:13:59.032887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.069833ms","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-03-13T17:13:59.033993Z","caller":"traceutil/trace.go:171","msg":"trace[250045704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"236.192889ms","start":"2026-03-13T17:13:58.797785Z","end":"2026-03-13T17:13:59.033977Z","steps":["trace[250045704] 'agreement among raft nodes before linearized reading' (duration: 235.051973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.032925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.625398ms","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-03-13T17:13:59.034176Z","caller":"traceutil/trace.go:171","msg":"trace[435291877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"237.870766ms","start":"2026-03-13T17:13:58.796245Z","end":"2026-03-13T17:13:59.034116Z","steps":["trace[435291877] 'agreement among raft nodes before linearized reading' (duration: 236.561166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.032966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.262713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-13T17:13:59.034278Z","caller":"traceutil/trace.go:171","msg":"trace[1561854025] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1009; }","duration":"247.608603ms","start":"2026-03-13T17:13:58.786654Z","end":"2026-03-13T17:13:59.034262Z","steps":["trace[1561854025] 'agreement among raft nodes before linearized reading' (duration: 246.266673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.033154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.794764ms","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-03-13T17:13:59.03439Z","caller":"traceutil/trace.go:171","msg":"trace[1740812314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"332.084484ms","start":"2026-03-13T17:13:58.70228Z","end":"2026-03-13T17:13:59.034365Z","steps":["trace[1740812314] 'agreement among raft nodes before linearized reading' (duration: 330.693072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.034468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:58.702257Z","time spent":"332.191086ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T17:13:59.268491Z","caller":"traceutil/trace.go:171","msg":"trace[186947702] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"231.150096ms","start":"2026-03-13T17:13:59.03731Z","end":"2026-03-13T17:13:59.26846Z","steps":["trace[186947702] 'process raft request' (duration: 168.532502ms)","trace[186947702] 'compare' (duration: 62.41315ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:59.268742Z","caller":"traceutil/trace.go:171","msg":"trace[477450053] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"230.626835ms","start":"2026-03-13T17:13:59.03809Z","end":"2026-03-13T17:13:59.268717Z","steps":["trace[477450053] 'process raft request' (duration: 230.522643ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:59.268767Z","caller":"traceutil/trace.go:171","msg":"trace[190796422] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"230.999362ms","start":"2026-03-13T17:13:59.037746Z","end":"2026-03-13T17:13:59.268745Z","steps":["trace[190796422] 'process raft request' (duration: 230.665735ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:59.268902Z","caller":"traceutil/trace.go:171","msg":"trace[2012280771] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"230.034363ms","start":"2026-03-13T17:13:59.038857Z","end":"2026-03-13T17:13:59.268891Z","steps":["trace[2012280771] 'process raft request' (duration: 229.807447ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:59.268915Z","caller":"traceutil/trace.go:171","msg":"trace[1236508317] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1054; }","duration":"226.398581ms","start":"2026-03-13T17:13:59.042499Z","end":"2026-03-13T17:13:59.268898Z","steps":["trace[1236508317] 'read index received' (duration: 163.294495ms)","trace[1236508317] 'applied index is now lower than readState.Index' (duration: 63.102936ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:59.268924Z","caller":"traceutil/trace.go:171","msg":"trace[456041766] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"229.951201ms","start":"2026-03-13T17:13:59.038956Z","end":"2026-03-13T17:13:59.268907Z","steps":["trace[456041766] 'process raft request' (duration: 229.836667ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:59.26894Z","caller":"traceutil/trace.go:171","msg":"trace[1467851167] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"227.403653ms","start":"2026-03-13T17:13:59.041526Z","end":"2026-03-13T17:13:59.268929Z","steps":["trace[1467851167] 'process raft request' (duration: 227.330412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:59.269001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.485883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:59.269022Z","caller":"traceutil/trace.go:171","msg":"trace[1527620094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"226.527153ms","start":"2026-03-13T17:13:59.042488Z","end":"2026-03-13T17:13:59.269015Z","steps":["trace[1527620094] 'agreement among raft nodes before linearized reading' (duration: 226.468662ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:15:47.01195Z","caller":"traceutil/trace.go:171","msg":"trace[1823968691] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"111.766274ms","start":"2026-03-13T17:15:46.900059Z","end":"2026-03-13T17:15:47.011826Z","steps":["trace[1823968691] 'read index received' (duration: 111.540199ms)","trace[1823968691] 'applied index is now lower than readState.Index' (duration: 224.575µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:15:47.01203Z","caller":"traceutil/trace.go:171","msg":"trace[372556702] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"125.831593ms","start":"2026-03-13T17:15:46.886171Z","end":"2026-03-13T17:15:47.012003Z","steps":["trace[372556702] 'process raft request' (duration: 125.484855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:47.012234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.157354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-13T17:15:47.0123Z","caller":"traceutil/trace.go:171","msg":"trace[1368917377] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1453; }","duration":"112.278185ms","start":"2026-03-13T17:15:46.900007Z","end":"2026-03-13T17:15:47.012285Z","steps":["trace[1368917377] 'agreement among raft nodes before linearized reading' (duration: 112.085851ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:15:47.130016Z","caller":"traceutil/trace.go:171","msg":"trace[587314113] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"112.305566ms","start":"2026-03-13T17:15:47.017672Z","end":"2026-03-13T17:15:47.129977Z","steps":["trace[587314113] 'process raft request' (duration: 61.29441ms)","trace[587314113] 'compare' (duration: 50.866473ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:16:27.374822Z","caller":"traceutil/trace.go:171","msg":"trace[1449101208] linearizableReadLoop","detail":"{readStateIndex:1692; appliedIndex:1691; }","duration":"155.744964ms","start":"2026-03-13T17:16:27.219047Z","end":"2026-03-13T17:16:27.374792Z","steps":["trace[1449101208] 'read index received' (duration: 155.513308ms)","trace[1449101208] 'applied index is now lower than readState.Index' (duration: 229.846µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:16:27.375066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.99487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-13T17:16:27.375069Z","caller":"traceutil/trace.go:171","msg":"trace[1302460128] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"262.252664ms","start":"2026-03-13T17:16:27.112751Z","end":"2026-03-13T17:16:27.375004Z","steps":["trace[1302460128] 'process raft request' (duration: 261.779242ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:16:27.375111Z","caller":"traceutil/trace.go:171","msg":"trace[860455376] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1617; }","duration":"156.100552ms","start":"2026-03-13T17:16:27.218996Z","end":"2026-03-13T17:16:27.375097Z","steps":["trace[860455376] 'agreement among raft nodes before linearized reading' (duration: 155.913228ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:46.692955Z","caller":"traceutil/trace.go:171","msg":"trace[551231276] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2065; }","duration":"217.754382ms","start":"2026-03-13T17:17:46.475163Z","end":"2026-03-13T17:17:46.692917Z","steps":["trace[551231276] 'read index received' (duration: 217.64562ms)","trace[551231276] 'applied index is now lower than readState.Index' (duration: 107.352µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:17:46.693124Z","caller":"traceutil/trace.go:171","msg":"trace[1967718530] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"230.533079ms","start":"2026-03-13T17:17:46.462546Z","end":"2026-03-13T17:17:46.693079Z","steps":["trace[1967718530] 'process raft request' (duration: 230.075358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:46.693246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.06338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-13T17:17:46.693303Z","caller":"traceutil/trace.go:171","msg":"trace[2089779412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1975; }","duration":"218.170392ms","start":"2026-03-13T17:17:46.475116Z","end":"2026-03-13T17:17:46.693286Z","steps":["trace[2089779412] 'agreement among raft nodes before linearized reading' (duration: 217.974347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:46.694122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.291345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-13T17:17:46.694163Z","caller":"traceutil/trace.go:171","msg":"trace[568654358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1976; }","duration":"218.336266ms","start":"2026-03-13T17:17:46.475818Z","end":"2026-03-13T17:17:46.694154Z","steps":["trace[568654358] 'agreement among raft nodes before linearized reading' (duration: 218.202773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:46.694222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.907211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-13T17:17:46.694311Z","caller":"traceutil/trace.go:171","msg":"trace[1171233259] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1976; }","duration":"104.039294ms","start":"2026-03-13T17:17:46.590251Z","end":"2026-03-13T17:17:46.69429Z","steps":["trace[1171233259] 'agreement among raft nodes before linearized reading' (duration: 103.785568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:46.694404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.734018ms","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-03-13T17:17:46.694436Z","caller":"traceutil/trace.go:171","msg":"trace[320250465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1976; }","duration":"194.80575ms","start":"2026-03-13T17:17:46.499623Z","end":"2026-03-13T17:17:46.694429Z","steps":["trace[320250465] 'agreement among raft nodes before linearized reading' (duration: 194.725208ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:46.694648Z","caller":"traceutil/trace.go:171","msg":"trace[1171330101] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"140.042457ms","start":"2026-03-13T17:17:46.55459Z","end":"2026-03-13T17:17:46.694633Z","steps":["trace[1171330101] 'process raft request' (duration: 139.375283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:46.694328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.146353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-03-13T17:17:46.695032Z","caller":"traceutil/trace.go:171","msg":"trace[1157924748] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1976; }","duration":"150.884319ms","start":"2026-03-13T17:17:46.544123Z","end":"2026-03-13T17:17:46.695008Z","steps":["trace[1157924748] 'agreement among raft nodes before linearized reading' (duration: 150.149593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:46.695054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.591741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:119 size:92147"} {"level":"info","ts":"2026-03-13T17:17:46.695281Z","caller":"traceutil/trace.go:171","msg":"trace[289162733] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:119; response_revision:1976; }","duration":"205.834166ms","start":"2026-03-13T17:17:46.48943Z","end":"2026-03-13T17:17:46.695264Z","steps":["trace[289162733] 'agreement among raft nodes before linearized reading' (duration: 205.054659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:46.698598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.47387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-13T17:17:46.698705Z","caller":"traceutil/trace.go:171","msg":"trace[473244415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1976; }","duration":"214.642963ms","start":"2026-03-13T17:17:46.484036Z","end":"2026-03-13T17:17:46.698679Z","steps":["trace[473244415] 'agreement among raft nodes before linearized reading' (duration: 210.880078ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:49.847179Z","caller":"traceutil/trace.go:171","msg":"trace[716422608] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"284.528704ms","start":"2026-03-13T17:17:49.562623Z","end":"2026-03-13T17:17:49.847152Z","steps":["trace[716422608] 'read index received' (duration: 284.308188ms)","trace[716422608] 'applied index is now lower than readState.Index' (duration: 219.296µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:17:49.847247Z","caller":"traceutil/trace.go:171","msg":"trace[146810613] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"337.680961ms","start":"2026-03-13T17:17:49.509531Z","end":"2026-03-13T17:17:49.847212Z","steps":["trace[146810613] 'process raft request' (duration: 337.476487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:49.847438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:17:49.509505Z","time spent":"337.828124ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:17:49.84748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.840021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-wl7tj\" ","response":"range_response_count:1 size:11386"} {"level":"info","ts":"2026-03-13T17:17:49.847665Z","caller":"traceutil/trace.go:171","msg":"trace[1878788633] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-wl7tj; range_end:; response_count:1; response_revision:1991; }","duration":"284.983114ms","start":"2026-03-13T17:17:49.562584Z","end":"2026-03-13T17:17:49.847567Z","steps":["trace[1878788633] 'agreement among raft nodes before linearized reading' (duration: 284.700498ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:50.955315Z","caller":"traceutil/trace.go:171","msg":"trace[704641188] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"145.99725ms","start":"2026-03-13T17:17:50.809274Z","end":"2026-03-13T17:17:50.955271Z","steps":["trace[704641188] 'process raft request' (duration: 145.669453ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:50.955524Z","caller":"traceutil/trace.go:171","msg":"trace[547422496] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"146.46161ms","start":"2026-03-13T17:17:50.80905Z","end":"2026-03-13T17:17:50.955511Z","steps":["trace[547422496] 'process raft request' (duration: 109.633238ms)","trace[547422496] 'compare' (duration: 36.100866ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:17:50.955684Z","caller":"traceutil/trace.go:171","msg":"trace[1934946699] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"145.734375ms","start":"2026-03-13T17:17:50.80992Z","end":"2026-03-13T17:17:50.955655Z","steps":["trace[1934946699] 'process raft request' (duration: 145.06768ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:50.955744Z","caller":"traceutil/trace.go:171","msg":"trace[1048922186] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"145.185372ms","start":"2026-03-13T17:17:50.810492Z","end":"2026-03-13T17:17:50.955677Z","steps":["trace[1048922186] 'process raft request' (duration: 144.558128ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:50.955977Z","caller":"traceutil/trace.go:171","msg":"trace[1815174920] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"145.609491ms","start":"2026-03-13T17:17:50.810355Z","end":"2026-03-13T17:17:50.955964Z","steps":["trace[1815174920] 'process raft request' (duration: 144.668221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:51.372759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.659378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-4bcg8\" ","response":"range_response_count:1 size:13723"} {"level":"info","ts":"2026-03-13T17:17:51.372928Z","caller":"traceutil/trace.go:171","msg":"trace[322150134] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-4bcg8; range_end:; response_count:1; response_revision:1998; }","duration":"242.789061ms","start":"2026-03-13T17:17:51.130036Z","end":"2026-03-13T17:17:51.372825Z","steps":["trace[322150134] 'range keys from in-memory index tree' (duration: 242.428743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:52.214158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.139223ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-13T17:17:52.214245Z","caller":"traceutil/trace.go:171","msg":"trace[1112462796] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2090; }","duration":"242.189348ms","start":"2026-03-13T17:17:51.972044Z","end":"2026-03-13T17:17:52.214234Z","steps":["trace[1112462796] 'read index received' (duration: 109.024734ms)","trace[1112462796] 'applied index is now lower than readState.Index' (duration: 133.163524ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:17:52.214384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.338212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-sldrl\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-03-13T17:17:52.214407Z","caller":"traceutil/trace.go:171","msg":"trace[746899327] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-sldrl; range_end:; response_count:1; response_revision:1998; }","duration":"242.399643ms","start":"2026-03-13T17:17:51.972001Z","end":"2026-03-13T17:17:52.214401Z","steps":["trace[746899327] 'agreement among raft nodes before linearized reading' (duration: 242.27731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:53.065439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.198549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T17:17:53.065459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.256848ms","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-03-13T17:17:53.065513Z","caller":"traceutil/trace.go:171","msg":"trace[1831742301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2000; }","duration":"105.316181ms","start":"2026-03-13T17:17:52.960175Z","end":"2026-03-13T17:17:53.065491Z","steps":["trace[1831742301] 'range keys from in-memory index tree' (duration: 104.943183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:53.065515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.868919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-13T17:17:53.065584Z","caller":"traceutil/trace.go:171","msg":"trace[443096800] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2000; }","duration":"103.967852ms","start":"2026-03-13T17:17:52.961599Z","end":"2026-03-13T17:17:53.065567Z","steps":["trace[443096800] 'range keys from in-memory index tree' (duration: 103.760977ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:53.065533Z","caller":"traceutil/trace.go:171","msg":"trace[1327689710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2000; }","duration":"104.374931ms","start":"2026-03-13T17:17:52.961141Z","end":"2026-03-13T17:17:53.065516Z","steps":["trace[1327689710] 'range keys from in-memory index tree' (duration: 104.071664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:53.065429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.252438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-13T17:17:53.065789Z","caller":"traceutil/trace.go:171","msg":"trace[1412363908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2000; }","duration":"104.664628ms","start":"2026-03-13T17:17:52.961109Z","end":"2026-03-13T17:17:53.065774Z","steps":["trace[1412363908] 'range keys from in-memory index tree' (duration: 104.030793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:53.065439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.38997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rsw48\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-03-13T17:17:53.065913Z","caller":"traceutil/trace.go:171","msg":"trace[1765314709] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rsw48; range_end:; response_count:1; response_revision:2000; }","duration":"344.884182ms","start":"2026-03-13T17:17:52.721018Z","end":"2026-03-13T17:17:53.065902Z","steps":["trace[1765314709] 'range keys from in-memory index tree' (duration: 344.214696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:53.065946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:17:52.721004Z","time spent":"344.929383ms","remote":"127.0.0.1:54538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18545,"request content":"key:\"/registry/pods/kube-system/cilium-rsw48\" "} {"level":"warn","ts":"2026-03-13T17:17:53.066326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.23565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-13T17:17:53.066524Z","caller":"traceutil/trace.go:171","msg":"trace[1527324406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2000; }","duration":"105.544137ms","start":"2026-03-13T17:17:52.960961Z","end":"2026-03-13T17:17:53.066505Z","steps":["trace[1527324406] 'range keys from in-memory index tree' (duration: 104.530224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:17:53.066368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.195975ms","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-03-13T17:17:53.066866Z","caller":"traceutil/trace.go:171","msg":"trace[942085678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2000; }","duration":"258.727127ms","start":"2026-03-13T17:17:52.808125Z","end":"2026-03-13T17:17:53.066852Z","steps":["trace[942085678] 'range keys from in-memory index tree' (duration: 257.080399ms)"],"step_count":1}