{"level":"warn","ts":"2026-02-26T21:01:16.003507Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T21:01:16.003602Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-02-26T21:01:16.003675Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T21:01:16.003685Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-26T21:01:16.003707Z","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-02-26T21:01:16.004547Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-02-26T21:01:16.004639Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-02-26T21:01:16.030222Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.331259ms"} {"level":"info","ts":"2026-02-26T21:01:16.048194Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-02-26T21:01:16.048281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T21:01:16.0483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-02-26T21:01:16.048308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T21:01:16.048316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-02-26T21:01:16.048347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-02-26T21:01:16.079925Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T21:01:16.081268Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T21:01:16.102033Z","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-02-26T21:01:16.105118Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T21:01:16.106239Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T21:01:16.106358Z","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-02-26T21:01:16.106402Z","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-02-26T21:01:16.106453Z","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-02-26T21:01:16.10967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-02-26T21:01:16.110499Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-26T21:01:16.11169Z","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-02-26T21:01:16.111952Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T21:01:16.111997Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T21:01:16.112122Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-26T21:01:16.112149Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-26T21:01:16.349417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T21:01:16.349489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T21:01:16.349536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-02-26T21:01:16.349563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-02-26T21:01:16.349579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-26T21:01:16.349607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-02-26T21:01:16.349625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-26T21:01:16.351753Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:16.352665Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T21:01:16.352952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T21:01:16.353001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T21:01:16.353234Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:16.353335Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:16.353378Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:16.355528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T21:01:16.36151Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-02-26T21:01:16.361758Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T21:01:16.361777Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T21:01:18.09432Z","caller":"traceutil/trace.go:171","msg":"trace[592397127] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"116.328161ms","start":"2026-02-26T21:01:17.977959Z","end":"2026-02-26T21:01:18.094287Z","steps":["trace[592397127] 'process raft request' (duration: 115.922591ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.094604Z","caller":"traceutil/trace.go:171","msg":"trace[22665690] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"116.604607ms","start":"2026-02-26T21:01:17.977979Z","end":"2026-02-26T21:01:18.094583Z","steps":["trace[22665690] 'process raft request' (duration: 115.92957ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.09468Z","caller":"traceutil/trace.go:171","msg":"trace[2095127214] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"117.017397ms","start":"2026-02-26T21:01:17.977644Z","end":"2026-02-26T21:01:18.094662Z","steps":["trace[2095127214] 'process raft request' (duration: 116.140456ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.094337Z","caller":"traceutil/trace.go:171","msg":"trace[1055139881] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"116.863654ms","start":"2026-02-26T21:01:17.977443Z","end":"2026-02-26T21:01:18.094307Z","steps":["trace[1055139881] 'read index received' (duration: 25.007432ms)","trace[1055139881] 'applied index is now lower than readState.Index' (duration: 91.853392ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:18.094806Z","caller":"traceutil/trace.go:171","msg":"trace[230573420] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"120.019488ms","start":"2026-02-26T21:01:17.974775Z","end":"2026-02-26T21:01:18.094794Z","steps":["trace[230573420] 'process raft request' (duration: 94.600867ms)","trace[230573420] 'compare' (duration: 23.782542ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:18.094822Z","caller":"traceutil/trace.go:171","msg":"trace[979462523] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"117.115739ms","start":"2026-02-26T21:01:17.977686Z","end":"2026-02-26T21:01:18.094802Z","steps":["trace[979462523] 'process raft request' (duration: 116.139206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:18.094966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.071458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-02-26T21:01:18.096799Z","caller":"traceutil/trace.go:171","msg":"trace[1623038699] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"119.032404ms","start":"2026-02-26T21:01:17.977426Z","end":"2026-02-26T21:01:18.096459Z","steps":["trace[1623038699] 'agreement among raft nodes before linearized reading' (duration: 116.984506ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.094941Z","caller":"traceutil/trace.go:171","msg":"trace[1782920650] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"116.961766ms","start":"2026-02-26T21:01:17.977817Z","end":"2026-02-26T21:01:18.094779Z","steps":["trace[1782920650] 'process raft request' (duration: 116.035143ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.095128Z","caller":"traceutil/trace.go:171","msg":"trace[1644743792] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"116.934385ms","start":"2026-02-26T21:01:17.978171Z","end":"2026-02-26T21:01:18.095105Z","steps":["trace[1644743792] 'process raft request' (duration: 115.767668ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:27.667375Z","caller":"traceutil/trace.go:171","msg":"trace[1298970162] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"104.364208ms","start":"2026-02-26T21:01:27.562984Z","end":"2026-02-26T21:01:27.667348Z","steps":["trace[1298970162] 'read index received' (duration: 104.171694ms)","trace[1298970162] 'applied index is now lower than readState.Index' (duration: 191.684µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:27.667434Z","caller":"traceutil/trace.go:171","msg":"trace[999078332] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"130.542418ms","start":"2026-02-26T21:01:27.536867Z","end":"2026-02-26T21:01:27.667409Z","steps":["trace[999078332] 'process raft request' (duration: 130.361803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:27.667567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.506051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-khnj2\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-02-26T21:01:27.66761Z","caller":"traceutil/trace.go:171","msg":"trace[1054457919] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-khnj2; range_end:; response_count:1; response_revision:350; }","duration":"104.649454ms","start":"2026-02-26T21:01:27.56295Z","end":"2026-02-26T21:01:27.667599Z","steps":["trace[1054457919] 'agreement among raft nodes before linearized reading' (duration: 104.504541ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.042134Z","caller":"traceutil/trace.go:171","msg":"trace[1575607693] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"362.099593ms","start":"2026-02-26T21:01:27.680009Z","end":"2026-02-26T21:01:28.042108Z","steps":["trace[1575607693] 'process raft request' (duration: 361.857577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.042781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:27.679991Z","time spent":"362.222645ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:28.924133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.99859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:01:28.92425Z","caller":"traceutil/trace.go:171","msg":"trace[384828310] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"874.653012ms","start":"2026-02-26T21:01:28.049579Z","end":"2026-02-26T21:01:28.924233Z","steps":["trace[384828310] 'process raft request' (duration: 177.094978ms)","trace[384828310] 'compare' (duration: 696.805876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:28.924334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:28.049555Z","time spent":"874.734114ms","remote":"127.0.0.1:48210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:28.924482Z","caller":"traceutil/trace.go:171","msg":"trace[883198672] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"660.650312ms","start":"2026-02-26T21:01:28.263826Z","end":"2026-02-26T21:01:28.924476Z","steps":["trace[883198672] 'read index received' (duration: 42.191µs)","trace[883198672] 'applied index is now lower than readState.Index' (duration: 660.607351ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:28.924495Z","caller":"traceutil/trace.go:171","msg":"trace[2011864012] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"550.18174ms","start":"2026-02-26T21:01:28.374296Z","end":"2026-02-26T21:01:28.924478Z","steps":["trace[2011864012] 'process raft request' (duration: 549.951074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.924603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.582963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T21:01:28.924626Z","caller":"traceutil/trace.go:171","msg":"trace[1174371032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"551.638274ms","start":"2026-02-26T21:01:28.37298Z","end":"2026-02-26T21:01:28.924618Z","steps":["trace[1174371032] 'agreement among raft nodes before linearized reading' (duration: 551.607043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.924653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:28.372957Z","time spent":"551.691005ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:01:28.924773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.36232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T21:01:28.924781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:28.374264Z","time spent":"550.340094ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:28.924801Z","caller":"traceutil/trace.go:171","msg":"trace[669482078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:353; }","duration":"549.416921ms","start":"2026-02-26T21:01:28.375378Z","end":"2026-02-26T21:01:28.924795Z","steps":["trace[669482078] 'agreement among raft nodes before linearized reading' (duration: 549.37376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.924905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:28.375365Z","time spent":"549.533804ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:01:28.924568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.741574ms","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-02-26T21:01:28.925181Z","caller":"traceutil/trace.go:171","msg":"trace[919288674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:353; }","duration":"661.381909ms","start":"2026-02-26T21:01:28.263781Z","end":"2026-02-26T21:01:28.925163Z","steps":["trace[919288674] 'agreement among raft nodes before linearized reading' (duration: 660.748564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.925248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:28.263759Z","time spent":"661.46383ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:01:29.50073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.59796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:01:29.500899Z","caller":"traceutil/trace.go:171","msg":"trace[2074772724] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"572.343314ms","start":"2026-02-26T21:01:28.928539Z","end":"2026-02-26T21:01:29.500882Z","steps":["trace[2074772724] 'process raft request' (duration: 339.508718ms)","trace[2074772724] 'compare' (duration: 232.498808ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:29.50096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:28.928516Z","time spent":"572.415595ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:29.501312Z","caller":"traceutil/trace.go:171","msg":"trace[406011028] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"572.526508ms","start":"2026-02-26T21:01:28.92872Z","end":"2026-02-26T21:01:29.501247Z","steps":["trace[406011028] 'process raft request' (duration: 572.104167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:29.501826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:28.928711Z","time spent":"572.713373ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:29.502628Z","caller":"traceutil/trace.go:171","msg":"trace[1709464004] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"573.631254ms","start":"2026-02-26T21:01:28.928982Z","end":"2026-02-26T21:01:29.502613Z","steps":["trace[1709464004] 'process raft request' (duration: 573.398148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:29.502702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:28.928972Z","time spent":"573.689696ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:30.987401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025631532s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-26T21:01:30.987559Z","caller":"traceutil/trace.go:171","msg":"trace[1910977637] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"1.313881598s","start":"2026-02-26T21:01:29.673656Z","end":"2026-02-26T21:01:30.987537Z","steps":["trace[1910977637] 'read index received' (duration: 288.052122ms)","trace[1910977637] 'applied index is now lower than readState.Index' (duration: 1.025827496s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:30.9876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:29.515857Z","time spent":"1.47173302s","remote":"127.0.0.1:47782","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T21:01:30.987729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.988202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:30.987776Z","caller":"traceutil/trace.go:171","msg":"trace[889640399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:356; }","duration":"837.079364ms","start":"2026-02-26T21:01:30.150679Z","end":"2026-02-26T21:01:30.987759Z","steps":["trace[889640399] 'agreement among raft nodes before linearized reading' (duration: 836.989352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:30.987828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:30.15066Z","time spent":"837.155886ms","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:01:30.987853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.49432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"warn","ts":"2026-02-26T21:01:30.987809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314086333s","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-02-26T21:01:30.987975Z","caller":"traceutil/trace.go:171","msg":"trace[1788407183] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:356; }","duration":"526.657653ms","start":"2026-02-26T21:01:30.461294Z","end":"2026-02-26T21:01:30.987952Z","steps":["trace[1788407183] 'agreement among raft nodes before linearized reading' (duration: 526.437908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:30.988035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:30.461279Z","time spent":"526.746426ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-26T21:01:30.988019Z","caller":"traceutil/trace.go:171","msg":"trace[1240539267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:356; }","duration":"1.31437032s","start":"2026-02-26T21:01:29.673623Z","end":"2026-02-26T21:01:30.987993Z","steps":["trace[1240539267] 'agreement among raft nodes before linearized reading' (duration: 1.314018442s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:30.988108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:29.673607Z","time spent":"1.314487642s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T21:01:31.305023Z","caller":"traceutil/trace.go:171","msg":"trace[2045057380] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:370; }","duration":"254.386275ms","start":"2026-02-26T21:01:31.050616Z","end":"2026-02-26T21:01:31.305002Z","steps":["trace[2045057380] 'read index received' (duration: 16.277554ms)","trace[2045057380] 'applied index is now lower than readState.Index' (duration: 238.107311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:31.305321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.938ms","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-02-26T21:01:31.305364Z","caller":"traceutil/trace.go:171","msg":"trace[2093887179] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:359; }","duration":"236.031272ms","start":"2026-02-26T21:01:31.069322Z","end":"2026-02-26T21:01:31.305353Z","steps":["trace[2093887179] 'agreement among raft nodes before linearized reading' (duration: 235.94314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:31.305422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.701453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:31.305582Z","caller":"traceutil/trace.go:171","msg":"trace[48810445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:359; }","duration":"254.979089ms","start":"2026-02-26T21:01:31.05058Z","end":"2026-02-26T21:01:31.305559Z","steps":["trace[48810445] 'agreement among raft nodes before linearized reading' (duration: 254.661521ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:31.305469Z","caller":"traceutil/trace.go:171","msg":"trace[1981363163] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"305.814243ms","start":"2026-02-26T21:01:30.999607Z","end":"2026-02-26T21:01:31.305421Z","steps":["trace[1981363163] 'process raft request' (duration: 305.087095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:31.306005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:30.999597Z","time spent":"306.248162ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:31.305044Z","caller":"traceutil/trace.go:171","msg":"trace[2116371134] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"309.14644ms","start":"2026-02-26T21:01:30.995808Z","end":"2026-02-26T21:01:31.304955Z","steps":["trace[2116371134] 'process raft request' (duration: 308.75012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:31.3081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:30.995771Z","time spent":"312.201322ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:36.704303Z","caller":"traceutil/trace.go:171","msg":"trace[2121092730] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"243.048798ms","start":"2026-02-26T21:01:36.46123Z","end":"2026-02-26T21:01:36.704279Z","steps":["trace[2121092730] 'process raft request' (duration: 242.842693ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:51.53594Z","caller":"traceutil/trace.go:171","msg":"trace[988440306] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"590.254236ms","start":"2026-02-26T21:01:50.945665Z","end":"2026-02-26T21:01:51.53592Z","steps":["trace[988440306] 'process raft request' (duration: 590.115253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:51.536056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:50.945641Z","time spent":"590.36427ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:01:51.536198Z","caller":"traceutil/trace.go:171","msg":"trace[1960228201] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:518; }","duration":"486.629256ms","start":"2026-02-26T21:01:51.049562Z","end":"2026-02-26T21:01:51.536191Z","steps":["trace[1960228201] 'read index received' (duration: 486.626096ms)","trace[1960228201] 'applied index is now lower than readState.Index' (duration: 2.65µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:51.536291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.726078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:51.536317Z","caller":"traceutil/trace.go:171","msg":"trace[620640478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"486.801749ms","start":"2026-02-26T21:01:51.04951Z","end":"2026-02-26T21:01:51.536312Z","steps":["trace[620640478] 'agreement among raft nodes before linearized reading' (duration: 486.712047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:51.536345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.049476Z","time spent":"486.855581ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T21:01:52.031246Z","caller":"traceutil/trace.go:171","msg":"trace[1071763930] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:518; }","duration":"492.858615ms","start":"2026-02-26T21:01:51.538366Z","end":"2026-02-26T21:01:52.031224Z","steps":["trace[1071763930] 'read index received' (duration: 492.763982ms)","trace[1071763930] 'applied index is now lower than readState.Index' (duration: 93.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:52.031318Z","caller":"traceutil/trace.go:171","msg":"trace[2143325243] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"682.428017ms","start":"2026-02-26T21:01:51.348854Z","end":"2026-02-26T21:01:52.031282Z","steps":["trace[2143325243] 'process raft request' (duration: 682.338375ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:52.031332Z","caller":"traceutil/trace.go:171","msg":"trace[851984125] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"882.845885ms","start":"2026-02-26T21:01:51.148411Z","end":"2026-02-26T21:01:52.031257Z","steps":["trace[851984125] 'process raft request' (duration: 882.650991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.031445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.348833Z","time spent":"682.54441ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:01:52.03153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.148374Z","time spent":"883.070291ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:01:52.03155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.172952ms","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-02-26T21:01:52.03159Z","caller":"traceutil/trace.go:171","msg":"trace[656304902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"493.271144ms","start":"2026-02-26T21:01:51.538306Z","end":"2026-02-26T21:01:52.031577Z","steps":["trace[656304902] 'agreement among raft nodes before linearized reading' (duration: 493.027778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.031628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.538281Z","time spent":"493.340156ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T21:01:52.308882Z","caller":"traceutil/trace.go:171","msg":"trace[313008996] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"766.369762ms","start":"2026-02-26T21:01:51.542487Z","end":"2026-02-26T21:01:52.308857Z","steps":["trace[313008996] 'process raft request' (duration: 766.073335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.309052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.542468Z","time spent":"766.490804ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:52.309171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.062101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:52.309046Z","caller":"traceutil/trace.go:171","msg":"trace[1689932775] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:520; }","duration":"277.706737ms","start":"2026-02-26T21:01:52.03131Z","end":"2026-02-26T21:01:52.309017Z","steps":["trace[1689932775] 'read index received' (duration: 277.277257ms)","trace[1689932775] 'applied index is now lower than readState.Index' (duration: 428.33µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:52.309213Z","caller":"traceutil/trace.go:171","msg":"trace[75218348] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:503; }","duration":"765.140063ms","start":"2026-02-26T21:01:51.544063Z","end":"2026-02-26T21:01:52.309203Z","steps":["trace[75218348] 'agreement among raft nodes before linearized reading' (duration: 765.03209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.309242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.544052Z","time spent":"765.184144ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-26T21:01:52.309303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.808428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T21:01:52.309336Z","caller":"traceutil/trace.go:171","msg":"trace[1976846264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"688.945242ms","start":"2026-02-26T21:01:51.620381Z","end":"2026-02-26T21:01:52.309326Z","steps":["trace[1976846264] 'agreement among raft nodes before linearized reading' (duration: 688.813119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.309353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.363699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T21:01:52.309354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.562306ms","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-02-26T21:01:52.309373Z","caller":"traceutil/trace.go:171","msg":"trace[2121540833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"694.433921ms","start":"2026-02-26T21:01:51.614932Z","end":"2026-02-26T21:01:52.309366Z","steps":["trace[2121540833] 'agreement among raft nodes before linearized reading' (duration: 694.38921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.309392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.614903Z","time spent":"694.485172ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:01:52.309399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.811316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:52.30943Z","caller":"traceutil/trace.go:171","msg":"trace[1568364176] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:503; }","duration":"364.888398ms","start":"2026-02-26T21:01:51.944532Z","end":"2026-02-26T21:01:52.30942Z","steps":["trace[1568364176] 'agreement among raft nodes before linearized reading' (duration: 364.850197ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:52.309407Z","caller":"traceutil/trace.go:171","msg":"trace[283282020] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"561.62312ms","start":"2026-02-26T21:01:51.747746Z","end":"2026-02-26T21:01:52.309369Z","steps":["trace[283282020] 'process raft request' (duration: 561.055147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.309456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.944504Z","time spent":"364.94735ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-26T21:01:52.3094Z","caller":"traceutil/trace.go:171","msg":"trace[553849913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:503; }","duration":"692.649548ms","start":"2026-02-26T21:01:51.616735Z","end":"2026-02-26T21:01:52.309385Z","steps":["trace[553849913] 'agreement among raft nodes before linearized reading' (duration: 692.520445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.30953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.747715Z","time spent":"561.759733ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:01:52.309599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.616715Z","time spent":"692.832673ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:01:52.309364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:51.620352Z","time spent":"689.006223ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T21:01:52.316693Z","caller":"traceutil/trace.go:171","msg":"trace[1856055748] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"280.201356ms","start":"2026-02-26T21:01:52.03647Z","end":"2026-02-26T21:01:52.316671Z","steps":["trace[1856055748] 'process raft request' (duration: 280.037322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.316767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.549497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:52.31675Z","caller":"traceutil/trace.go:171","msg":"trace[361298649] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"243.791035ms","start":"2026-02-26T21:01:52.072933Z","end":"2026-02-26T21:01:52.316724Z","steps":["trace[361298649] 'process raft request' (duration: 243.666452ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:52.316806Z","caller":"traceutil/trace.go:171","msg":"trace[874449494] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:505; }","duration":"171.602718ms","start":"2026-02-26T21:01:52.145185Z","end":"2026-02-26T21:01:52.316788Z","steps":["trace[874449494] 'agreement among raft nodes before linearized reading' (duration: 171.563837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.316721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.310921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:52.316859Z","caller":"traceutil/trace.go:171","msg":"trace[1362111667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"267.475986ms","start":"2026-02-26T21:01:52.049373Z","end":"2026-02-26T21:01:52.316848Z","steps":["trace[1362111667] 'agreement among raft nodes before linearized reading' (duration: 267.260701ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:58.543656Z","caller":"traceutil/trace.go:171","msg":"trace[1248560971] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"163.016585ms","start":"2026-02-26T21:01:58.380609Z","end":"2026-02-26T21:01:58.543626Z","steps":["trace[1248560971] 'process raft request' (duration: 162.781849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.77232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.040567ms","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-02-26T21:02:00.772404Z","caller":"traceutil/trace.go:171","msg":"trace[633103000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"177.19577ms","start":"2026-02-26T21:02:00.595189Z","end":"2026-02-26T21:02:00.772384Z","steps":["trace[633103000] 'range keys from in-memory index tree' (duration: 176.847463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.772555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.962345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:02:00.772684Z","caller":"traceutil/trace.go:171","msg":"trace[399773822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"177.141258ms","start":"2026-02-26T21:02:00.595516Z","end":"2026-02-26T21:02:00.772658Z","steps":["trace[399773822] 'range keys from in-memory index tree' (duration: 176.679348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.772705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.945339ms","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-02-26T21:02:00.772758Z","caller":"traceutil/trace.go:171","msg":"trace[956318143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"225.034661ms","start":"2026-02-26T21:02:00.547709Z","end":"2026-02-26T21:02:00.772744Z","steps":["trace[956318143] 'range keys from in-memory index tree' (duration: 224.807255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.772934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.870986ms","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-02-26T21:02:00.772985Z","caller":"traceutil/trace.go:171","msg":"trace[2118191609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:547; }","duration":"177.958779ms","start":"2026-02-26T21:02:00.595009Z","end":"2026-02-26T21:02:00.772968Z","steps":["trace[2118191609] 'range keys from in-memory index tree' (duration: 177.749433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.773826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.276647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T21:02:00.77393Z","caller":"traceutil/trace.go:171","msg":"trace[1681621799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"178.419229ms","start":"2026-02-26T21:02:00.595488Z","end":"2026-02-26T21:02:00.773907Z","steps":["trace[1681621799] 'range keys from in-memory index tree' (duration: 177.22396ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:00.912929Z","caller":"traceutil/trace.go:171","msg":"trace[234563931] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"135.202346ms","start":"2026-02-26T21:02:00.777706Z","end":"2026-02-26T21:02:00.912909Z","steps":["trace[234563931] 'process raft request' (duration: 135.090344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:02.424325Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.494358286s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:02:02.424792Z","caller":"traceutil/trace.go:171","msg":"trace[1537651714] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.646529735s","start":"2026-02-26T21:02:00.77824Z","end":"2026-02-26T21:02:02.42477Z","steps":["trace[1537651714] 'process raft request' (duration: 1.646469423s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:02.424881Z","caller":"traceutil/trace.go:171","msg":"trace[941049511] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.647105967s","start":"2026-02-26T21:02:00.77774Z","end":"2026-02-26T21:02:02.424846Z","steps":["trace[941049511] 'process raft request' (duration: 1.64680248s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:02.424916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:00.77823Z","time spent":"1.646611346s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:02:02.424891Z","caller":"traceutil/trace.go:171","msg":"trace[392530341] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.646746169s","start":"2026-02-26T21:02:00.778122Z","end":"2026-02-26T21:02:02.424868Z","steps":["trace[392530341] 'process raft request' (duration: 1.646553434s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:02.425031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:00.778111Z","time spent":"1.646849362s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:02.425028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:00.777709Z","time spent":"1.647245261s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:02:02.425147Z","caller":"traceutil/trace.go:171","msg":"trace[549467864] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.64677138s","start":"2026-02-26T21:02:00.778364Z","end":"2026-02-26T21:02:02.425136Z","steps":["trace[549467864] 'process raft request' (duration: 1.646378091s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:02.425158Z","caller":"traceutil/trace.go:171","msg":"trace[1680801760] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.639548298s","start":"2026-02-26T21:02:00.785595Z","end":"2026-02-26T21:02:02.425143Z","steps":["trace[1680801760] 'process raft request' (duration: 1.639438446s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:02.425153Z","caller":"traceutil/trace.go:171","msg":"trace[646828810] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:569; }","duration":"374.822592ms","start":"2026-02-26T21:02:02.050305Z","end":"2026-02-26T21:02:02.425128Z","steps":["trace[646828810] 'read index received' (duration: 374.196487ms)","trace[646828810] 'applied index is now lower than readState.Index' (duration: 625.375µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:02.425198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:00.778357Z","time spent":"1.646812261s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:02.425229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:00.785581Z","time spent":"1.63960878s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:02.425245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.921064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:02.425267Z","caller":"traceutil/trace.go:171","msg":"trace[590608298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"374.996287ms","start":"2026-02-26T21:02:02.050264Z","end":"2026-02-26T21:02:02.42526Z","steps":["trace[590608298] 'agreement among raft nodes before linearized reading' (duration: 374.940446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:02.425291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.050248Z","time spent":"375.038379ms","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:02:03.446711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518996706,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:03.968477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518996706,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:04.134577Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.544588296s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:02:04.13799Z","caller":"traceutil/trace.go:171","msg":"trace[530024197] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"1.201556021s","start":"2026-02-26T21:02:02.9364Z","end":"2026-02-26T21:02:04.137956Z","steps":["trace[530024197] 'read index received' (duration: 1.201246754s)","trace[530024197] 'applied index is now lower than readState.Index' (duration: 308.117µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:04.13818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087420712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:04.138229Z","caller":"traceutil/trace.go:171","msg":"trace[1225653007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"1.087546074s","start":"2026-02-26T21:02:03.050672Z","end":"2026-02-26T21:02:04.138218Z","steps":["trace[1225653007] 'agreement among raft nodes before linearized reading' (duration: 1.087447352s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:04.138057Z","caller":"traceutil/trace.go:171","msg":"trace[1828738242] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.54815933s","start":"2026-02-26T21:02:02.589863Z","end":"2026-02-26T21:02:04.138022Z","steps":["trace[1828738242] 'process raft request' (duration: 1.547879613s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:04.138289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:03.05065Z","time spent":"1.087627797s","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:02:04.138731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.589838Z","time spent":"1.54861541s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:04.144224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207781958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:02:04.144371Z","caller":"traceutil/trace.go:171","msg":"trace[1333871789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"1.207961853s","start":"2026-02-26T21:02:02.936351Z","end":"2026-02-26T21:02:04.144313Z","steps":["trace[1333871789] 'agreement among raft nodes before linearized reading' (duration: 1.202242767s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:04.144432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.93633Z","time spent":"1.208093695s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:02:04.376024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.674413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:04.376159Z","caller":"traceutil/trace.go:171","msg":"trace[910598642] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"224.62392ms","start":"2026-02-26T21:02:04.151507Z","end":"2026-02-26T21:02:04.376131Z","steps":["trace[910598642] 'process raft request' (duration: 59.761923ms)","trace[910598642] 'compare' (duration: 164.297554ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:04.376331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.797409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:04.376403Z","caller":"traceutil/trace.go:171","msg":"trace[400025219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"206.927272ms","start":"2026-02-26T21:02:04.169458Z","end":"2026-02-26T21:02:04.376385Z","steps":["trace[400025219] 'agreement among raft nodes before linearized reading' (duration: 206.764659ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:04.376139Z","caller":"traceutil/trace.go:171","msg":"trace[888295597] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"206.618085ms","start":"2026-02-26T21:02:04.169502Z","end":"2026-02-26T21:02:04.37612Z","steps":["trace[888295597] 'read index received' (duration: 41.711887ms)","trace[888295597] 'applied index is now lower than readState.Index' (duration: 164.904718ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:04.955327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518996715,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:05.457292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518996715,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:05.958313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518996715,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:06.459132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518996715,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:06.954189Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.500307959s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T21:02:07.239886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.413271ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:07.24001Z","caller":"traceutil/trace.go:171","msg":"trace[396427031] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:555; }","duration":"262.546204ms","start":"2026-02-26T21:02:06.97745Z","end":"2026-02-26T21:02:07.239996Z","steps":["trace[396427031] 'range keys from in-memory index tree' (duration: 262.39966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.240802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.238306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:07.240924Z","caller":"traceutil/trace.go:171","msg":"trace[1122173133] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"2.78711612s","start":"2026-02-26T21:02:04.453783Z","end":"2026-02-26T21:02:07.240899Z","steps":["trace[1122173133] 'process raft request' (duration: 2.500681439s)","trace[1122173133] 'compare' (duration: 285.340595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:07.241004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:04.453758Z","time spent":"2.787205352s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:02:07.311008Z","caller":"traceutil/trace.go:171","msg":"trace[1062566802] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"2.857191525s","start":"2026-02-26T21:02:04.453787Z","end":"2026-02-26T21:02:07.310979Z","steps":["trace[1062566802] 'process raft request' (duration: 2.856955099s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:07.311053Z","caller":"traceutil/trace.go:171","msg":"trace[1286490740] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"2.855629739s","start":"2026-02-26T21:02:04.455373Z","end":"2026-02-26T21:02:07.311003Z","steps":["trace[1286490740] 'process raft request' (duration: 2.855521576s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.311167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:04.453758Z","time spent":"2.857331579s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:07.311257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:04.455363Z","time spent":"2.855766702s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:02:07.326806Z","caller":"traceutil/trace.go:171","msg":"trace[844566240] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:576; }","duration":"2.872476325s","start":"2026-02-26T21:02:04.454306Z","end":"2026-02-26T21:02:07.326782Z","steps":["trace[844566240] 'read index received' (duration: 2.500187896s)","trace[844566240] 'applied index is now lower than readState.Index' (duration: 372.287509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:07.327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.872697321s","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-02-26T21:02:07.32703Z","caller":"traceutil/trace.go:171","msg":"trace[1550031458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:558; }","duration":"2.872758082s","start":"2026-02-26T21:02:04.454264Z","end":"2026-02-26T21:02:07.327022Z","steps":["trace[1550031458] 'agreement among raft nodes before linearized reading' (duration: 2.872638809s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.327051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:04.454255Z","time spent":"2.872791513s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T21:02:07.327064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.77155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:02:07.327088Z","caller":"traceutil/trace.go:171","msg":"trace[1135587605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"941.844262ms","start":"2026-02-26T21:02:06.385235Z","end":"2026-02-26T21:02:07.32708Z","steps":["trace[1135587605] 'agreement among raft nodes before linearized reading' (duration: 941.78494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.32712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.385208Z","time spent":"941.895873ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:02:07.326998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255232005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:07.32719Z","caller":"traceutil/trace.go:171","msg":"trace[610717295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"1.255474811s","start":"2026-02-26T21:02:06.071709Z","end":"2026-02-26T21:02:07.327183Z","steps":["trace[610717295] 'agreement among raft nodes before linearized reading' (duration: 1.255253846s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.327207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.071686Z","time spent":"1.255516672s","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T21:02:09.86604Z","caller":"traceutil/trace.go:171","msg":"trace[2033439770] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"117.664698ms","start":"2026-02-26T21:02:09.748343Z","end":"2026-02-26T21:02:09.866008Z","steps":["trace[2033439770] 'process raft request' (duration: 117.499354ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:10.880653Z","caller":"traceutil/trace.go:171","msg":"trace[1936152545] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"125.939247ms","start":"2026-02-26T21:02:10.754685Z","end":"2026-02-26T21:02:10.880625Z","steps":["trace[1936152545] 'process raft request' (duration: 125.685611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:11.669673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.353219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:02:11.669734Z","caller":"traceutil/trace.go:171","msg":"trace[589446186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"286.444882ms","start":"2026-02-26T21:02:11.383275Z","end":"2026-02-26T21:02:11.66972Z","steps":["trace[589446186] 'range keys from in-memory index tree' (duration: 286.226537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:11.669801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.007134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T21:02:11.66983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.327867ms","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-02-26T21:02:11.669876Z","caller":"traceutil/trace.go:171","msg":"trace[147794026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"344.090186ms","start":"2026-02-26T21:02:11.325767Z","end":"2026-02-26T21:02:11.669858Z","steps":["trace[147794026] 'range keys from in-memory index tree' (duration: 343.889231ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:11.669901Z","caller":"traceutil/trace.go:171","msg":"trace[763639708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"345.455ms","start":"2026-02-26T21:02:11.324426Z","end":"2026-02-26T21:02:11.669881Z","steps":["trace[763639708] 'range keys from in-memory index tree' (duration: 345.141422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:11.669912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:11.325724Z","time spent":"344.180108ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:02:11.669942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:11.324401Z","time spent":"345.534741ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:02:11.670086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.338513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-26T21:02:11.67011Z","caller":"traceutil/trace.go:171","msg":"trace[1755693289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"389.399634ms","start":"2026-02-26T21:02:11.280703Z","end":"2026-02-26T21:02:11.670102Z","steps":["trace[1755693289] 'range keys from in-memory index tree' (duration: 388.616044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:11.670128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:11.280683Z","time spent":"389.440675ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:02:11.670462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.298157ms","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-02-26T21:02:11.670503Z","caller":"traceutil/trace.go:171","msg":"trace[811064719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:583; }","duration":"295.379539ms","start":"2026-02-26T21:02:11.375115Z","end":"2026-02-26T21:02:11.670495Z","steps":["trace[811064719] 'range keys from in-memory index tree' (duration: 294.19093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:13.103595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.504751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d7vr2\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-02-26T21:02:13.103687Z","caller":"traceutil/trace.go:171","msg":"trace[563935442] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d7vr2; range_end:; response_count:1; response_revision:592; }","duration":"345.649044ms","start":"2026-02-26T21:02:12.758014Z","end":"2026-02-26T21:02:13.103663Z","steps":["trace[563935442] 'range keys from in-memory index tree' (duration: 345.308697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:13.103729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:12.757996Z","time spent":"345.725057ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18356,"request content":"key:\"/registry/pods/kube-system/cilium-d7vr2\" "} {"level":"info","ts":"2026-02-26T21:02:13.325909Z","caller":"traceutil/trace.go:171","msg":"trace[63480124] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"208.487876ms","start":"2026-02-26T21:02:13.1174Z","end":"2026-02-26T21:02:13.325888Z","steps":["trace[63480124] 'process raft request' (duration: 208.356341ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:28.943266Z","caller":"traceutil/trace.go:171","msg":"trace[142822558] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"602.353687ms","start":"2026-02-26T21:02:28.340882Z","end":"2026-02-26T21:02:28.943235Z","steps":["trace[142822558] 'process raft request' (duration: 602.150032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:28.943494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:28.340855Z","time spent":"602.529211ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:02:29.999355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.735978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:29.999928Z","caller":"traceutil/trace.go:171","msg":"trace[29264761] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:748; }","duration":"162.42748ms","start":"2026-02-26T21:02:29.837451Z","end":"2026-02-26T21:02:29.999879Z","steps":["trace[29264761] 'read index received' (duration: 60.981225ms)","trace[29264761] 'applied index is now lower than readState.Index' (duration: 101.445245ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:30.000067Z","caller":"traceutil/trace.go:171","msg":"trace[1951598889] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"210.092574ms","start":"2026-02-26T21:02:29.789954Z","end":"2026-02-26T21:02:30.000046Z","steps":["trace[1951598889] 'process raft request' (duration: 108.556266ms)","trace[1951598889] 'compare' (duration: 100.40613ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:30.000157Z","caller":"traceutil/trace.go:171","msg":"trace[148718070] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"210.072193ms","start":"2026-02-26T21:02:29.790023Z","end":"2026-02-26T21:02:30.000095Z","steps":["trace[148718070] 'process raft request' (duration: 209.730275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:30.000218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.729277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:02:30.000299Z","caller":"traceutil/trace.go:171","msg":"trace[321550117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"162.882632ms","start":"2026-02-26T21:02:29.837394Z","end":"2026-02-26T21:02:30.000276Z","steps":["trace[321550117] 'agreement among raft nodes before linearized reading' (duration: 162.659346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:30.000283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.430431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T21:02:30.000363Z","caller":"traceutil/trace.go:171","msg":"trace[1922083682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"105.563484ms","start":"2026-02-26T21:02:29.894782Z","end":"2026-02-26T21:02:30.000346Z","steps":["trace[1922083682] 'agreement among raft nodes before linearized reading' (duration: 105.347359ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:30.167287Z","caller":"traceutil/trace.go:171","msg":"trace[1649000392] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"161.631691ms","start":"2026-02-26T21:02:30.00562Z","end":"2026-02-26T21:02:30.167251Z","steps":["trace[1649000392] 'process raft request' (duration: 101.65107ms)","trace[1649000392] 'compare' (duration: 59.842928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:31.253712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.109585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:31.253788Z","caller":"traceutil/trace.go:171","msg":"trace[962443161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"203.245618ms","start":"2026-02-26T21:02:31.050525Z","end":"2026-02-26T21:02:31.253771Z","steps":["trace[962443161] 'range keys from in-memory index tree' (duration: 202.992702ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:32.027749Z","caller":"traceutil/trace.go:171","msg":"trace[1441453420] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"193.603924ms","start":"2026-02-26T21:02:31.8341Z","end":"2026-02-26T21:02:32.027704Z","steps":["trace[1441453420] 'process raft request' (duration: 193.460641ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:32.168318Z","caller":"traceutil/trace.go:171","msg":"trace[991187660] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:753; }","duration":"129.130807ms","start":"2026-02-26T21:02:32.039026Z","end":"2026-02-26T21:02:32.168156Z","steps":["trace[991187660] 'read index received' (duration: 64.144913ms)","trace[991187660] 'applied index is now lower than readState.Index' (duration: 64.983123ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:32.168282Z","caller":"traceutil/trace.go:171","msg":"trace[486165853] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"136.060403ms","start":"2026-02-26T21:02:32.032175Z","end":"2026-02-26T21:02:32.168235Z","steps":["trace[486165853] 'process raft request' (duration: 135.852408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:32.168454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.22423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:02:32.168457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.405913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:02:32.168485Z","caller":"traceutil/trace.go:171","msg":"trace[82584195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"106.315183ms","start":"2026-02-26T21:02:32.062162Z","end":"2026-02-26T21:02:32.168477Z","steps":["trace[82584195] 'agreement among raft nodes before linearized reading' (duration: 106.245172ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:32.168499Z","caller":"traceutil/trace.go:171","msg":"trace[1472936772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"129.505215ms","start":"2026-02-26T21:02:32.038981Z","end":"2026-02-26T21:02:32.168486Z","steps":["trace[1472936772] 'agreement among raft nodes before linearized reading' (duration: 129.317111ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:34.180759Z","caller":"traceutil/trace.go:171","msg":"trace[1345964133] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"143.428581ms","start":"2026-02-26T21:02:34.037289Z","end":"2026-02-26T21:02:34.180718Z","steps":["trace[1345964133] 'process raft request' (duration: 143.167015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:34.720569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518997064,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T21:02:34.820915Z","caller":"traceutil/trace.go:171","msg":"trace[1655225727] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"636.585777ms","start":"2026-02-26T21:02:34.184305Z","end":"2026-02-26T21:02:34.820891Z","steps":["trace[1655225727] 'process raft request' (duration: 636.535045ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:34.820969Z","caller":"traceutil/trace.go:171","msg":"trace[1567448104] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"637.114769ms","start":"2026-02-26T21:02:34.183821Z","end":"2026-02-26T21:02:34.820935Z","steps":["trace[1567448104] 'process raft request' (duration: 580.527328ms)","trace[1567448104] 'compare' (duration: 56.351294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:34.821047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:34.184297Z","time spent":"636.685189ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:34.821147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:34.183806Z","time spent":"637.254202ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:35.208513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.62211ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T21:02:35.208593Z","caller":"traceutil/trace.go:171","msg":"trace[1679266276] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:758; }","duration":"989.061675ms","start":"2026-02-26T21:02:34.219516Z","end":"2026-02-26T21:02:35.208578Z","steps":["trace[1679266276] 'read index received' (duration: 544.976528ms)","trace[1679266276] 'applied index is now lower than readState.Index' (duration: 444.083807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:35.208783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.227308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T21:02:35.208871Z","caller":"traceutil/trace.go:171","msg":"trace[767731626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:731; }","duration":"989.394673ms","start":"2026-02-26T21:02:34.21946Z","end":"2026-02-26T21:02:35.208855Z","steps":["trace[767731626] 'agreement among raft nodes before linearized reading' (duration: 989.169237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:35.208909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:34.21944Z","time spent":"989.462405ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:02:35.271754Z","caller":"traceutil/trace.go:171","msg":"trace[1566480173] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"480.974409ms","start":"2026-02-26T21:02:34.790738Z","end":"2026-02-26T21:02:35.271713Z","steps":["trace[1566480173] 'process raft request' (duration: 480.745603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:35.271781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003526406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-26T21:02:35.271863Z","caller":"traceutil/trace.go:171","msg":"trace[709766566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"1.00365478s","start":"2026-02-26T21:02:34.268182Z","end":"2026-02-26T21:02:35.271836Z","steps":["trace[709766566] 'agreement among raft nodes before linearized reading' (duration: 1.003462444s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:35.271934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:34.268153Z","time spent":"1.003774223s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:02:35.271945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:34.790703Z","time spent":"481.115352ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:35.271975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.164897ms","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-02-26T21:02:35.272034Z","caller":"traceutil/trace.go:171","msg":"trace[849857050] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:732; }","duration":"191.251709ms","start":"2026-02-26T21:02:35.080767Z","end":"2026-02-26T21:02:35.272018Z","steps":["trace[849857050] 'agreement among raft nodes before linearized reading' (duration: 191.116036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:35.272092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.07207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:35.272193Z","caller":"traceutil/trace.go:171","msg":"trace[1576022557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"221.187103ms","start":"2026-02-26T21:02:35.050985Z","end":"2026-02-26T21:02:35.272172Z","steps":["trace[1576022557] 'agreement among raft nodes before linearized reading' (duration: 221.006598ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:46.981438Z","caller":"traceutil/trace.go:171","msg":"trace[1076940530] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"101.180399ms","start":"2026-02-26T21:02:46.88022Z","end":"2026-02-26T21:02:46.981401Z","steps":["trace[1076940530] 'process raft request' (duration: 100.997234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:49.552366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518997179,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T21:02:49.963063Z","caller":"traceutil/trace.go:171","msg":"trace[572978109] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"937.498378ms","start":"2026-02-26T21:02:49.025541Z","end":"2026-02-26T21:02:49.963039Z","steps":["trace[572978109] 'process raft request' (duration: 937.357504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:49.963208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.025521Z","time spent":"937.61653ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:02:49.966454Z","caller":"traceutil/trace.go:171","msg":"trace[1909653705] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"914.503321ms","start":"2026-02-26T21:02:49.051928Z","end":"2026-02-26T21:02:49.966431Z","steps":["trace[1909653705] 'read index received' (duration: 911.029067ms)","trace[1909653705] 'applied index is now lower than readState.Index' (duration: 3.473244ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:49.966563Z","caller":"traceutil/trace.go:171","msg":"trace[1207239887] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"939.927636ms","start":"2026-02-26T21:02:49.026627Z","end":"2026-02-26T21:02:49.966555Z","steps":["trace[1207239887] 'process raft request' (duration: 939.671849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:49.96668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.026614Z","time spent":"939.975036ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:49.966684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.747016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:49.966739Z","caller":"traceutil/trace.go:171","msg":"trace[765018598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"914.864579ms","start":"2026-02-26T21:02:49.05186Z","end":"2026-02-26T21:02:49.966724Z","steps":["trace[765018598] 'agreement among raft nodes before linearized reading' (duration: 914.785727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:49.96678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.05184Z","time spent":"914.933281ms","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:02:49.966838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.092752ms","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-02-26T21:02:49.967038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.743015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T21:02:49.967075Z","caller":"traceutil/trace.go:171","msg":"trace[701781341] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:775; }","duration":"492.820147ms","start":"2026-02-26T21:02:49.474246Z","end":"2026-02-26T21:02:49.967066Z","steps":["trace[701781341] 'agreement among raft nodes before linearized reading' (duration: 492.707334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:49.967087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.173675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T21:02:49.967101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.474222Z","time spent":"492.874027ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-02-26T21:02:49.967129Z","caller":"traceutil/trace.go:171","msg":"trace[963661085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"517.260747ms","start":"2026-02-26T21:02:49.449857Z","end":"2026-02-26T21:02:49.967117Z","steps":["trace[963661085] 'agreement among raft nodes before linearized reading' (duration: 517.139264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:49.967172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.449834Z","time spent":"517.329659ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:02:49.967198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.508916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:49.967496Z","caller":"traceutil/trace.go:171","msg":"trace[1130688247] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:775; }","duration":"358.903036ms","start":"2026-02-26T21:02:49.608572Z","end":"2026-02-26T21:02:49.967475Z","steps":["trace[1130688247] 'agreement among raft nodes before linearized reading' (duration: 358.454565ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:49.966924Z","caller":"traceutil/trace.go:171","msg":"trace[1419398486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"588.242325ms","start":"2026-02-26T21:02:49.378661Z","end":"2026-02-26T21:02:49.966904Z","steps":["trace[1419398486] 'agreement among raft nodes before linearized reading' (duration: 587.995549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:49.967565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.378641Z","time spent":"588.913641ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:02:49.967564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.608555Z","time spent":"358.995627ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:02:50.418155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.598177ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-26T21:02:50.418312Z","caller":"traceutil/trace.go:171","msg":"trace[1158720023] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"450.03737ms","start":"2026-02-26T21:02:49.968237Z","end":"2026-02-26T21:02:50.418274Z","steps":["trace[1158720023] 'read index received' (duration: 346.31205ms)","trace[1158720023] 'applied index is now lower than readState.Index' (duration: 103.7233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:50.418319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.968107Z","time spent":"450.204294ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T21:02:50.418435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.186013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:50.418484Z","caller":"traceutil/trace.go:171","msg":"trace[1520949930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"450.378708ms","start":"2026-02-26T21:02:49.968093Z","end":"2026-02-26T21:02:50.418472Z","steps":["trace[1520949930] 'agreement among raft nodes before linearized reading' (duration: 450.290816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:50.418522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.968077Z","time spent":"450.438389ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T21:02:50.4284Z","caller":"traceutil/trace.go:171","msg":"trace[843764025] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"241.76211ms","start":"2026-02-26T21:02:50.186618Z","end":"2026-02-26T21:02:50.42838Z","steps":["trace[843764025] 'process raft request' (duration: 241.730879ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:50.428505Z","caller":"traceutil/trace.go:171","msg":"trace[36989811] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"457.297096ms","start":"2026-02-26T21:02:49.971171Z","end":"2026-02-26T21:02:50.428468Z","steps":["trace[36989811] 'process raft request' (duration: 457.029639ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:50.428612Z","caller":"traceutil/trace.go:171","msg":"trace[1702428770] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"457.368108ms","start":"2026-02-26T21:02:49.971237Z","end":"2026-02-26T21:02:50.428605Z","steps":["trace[1702428770] 'process raft request' (duration: 457.085721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:50.428694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.97123Z","time spent":"457.399369ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:50.428726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:49.971152Z","time spent":"457.479851ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:50.428586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.614361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:50.428846Z","caller":"traceutil/trace.go:171","msg":"trace[1375132127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"104.957409ms","start":"2026-02-26T21:02:50.323879Z","end":"2026-02-26T21:02:50.428836Z","steps":["trace[1375132127] 'agreement among raft nodes before linearized reading' (duration: 104.633092ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:50.646645Z","caller":"traceutil/trace.go:171","msg":"trace[27511234] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"214.998874ms","start":"2026-02-26T21:02:50.431605Z","end":"2026-02-26T21:02:50.646604Z","steps":["trace[27511234] 'process raft request' (duration: 204.687254ms)","trace[27511234] 'compare' (duration: 10.088794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:50.646812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.44375ms","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-02-26T21:02:50.646857Z","caller":"traceutil/trace.go:171","msg":"trace[1241603282] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:780; }","duration":"150.531283ms","start":"2026-02-26T21:02:50.496315Z","end":"2026-02-26T21:02:50.646846Z","steps":["trace[1241603282] 'agreement among raft nodes before linearized reading' (duration: 150.44275ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:50.646719Z","caller":"traceutil/trace.go:171","msg":"trace[684036619] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"150.129813ms","start":"2026-02-26T21:02:50.496361Z","end":"2026-02-26T21:02:50.64649Z","steps":["trace[684036619] 'read index received' (duration: 139.812233ms)","trace[684036619] 'applied index is now lower than readState.Index' (duration: 10.3168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:01.419849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518997361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:03:01.443492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.817357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T21:03:01.443546Z","caller":"traceutil/trace.go:171","msg":"trace[1717936870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:855; }","duration":"973.910749ms","start":"2026-02-26T21:03:00.469624Z","end":"2026-02-26T21:03:01.443535Z","steps":["trace[1717936870] 'range keys from in-memory index tree' (duration: 973.577431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:01.443569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:00.469607Z","time spent":"973.95723ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:01.443566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.233451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:03:01.443636Z","caller":"traceutil/trace.go:171","msg":"trace[811218190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:855; }","duration":"942.371065ms","start":"2026-02-26T21:03:00.501246Z","end":"2026-02-26T21:03:01.443617Z","steps":["trace[811218190] 'range keys from in-memory index tree' (duration: 942.041817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:01.443674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:00.501215Z","time spent":"942.450706ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:03:01.447808Z","caller":"traceutil/trace.go:171","msg":"trace[216872881] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"528.811177ms","start":"2026-02-26T21:03:00.918978Z","end":"2026-02-26T21:03:01.447789Z","steps":["trace[216872881] 'read index received' (duration: 527.753731ms)","trace[216872881] 'applied index is now lower than readState.Index' (duration: 1.056526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:01.448077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.090624ms","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-02-26T21:03:01.448103Z","caller":"traceutil/trace.go:171","msg":"trace[934691675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"529.150226ms","start":"2026-02-26T21:03:00.918946Z","end":"2026-02-26T21:03:01.448097Z","steps":["trace[934691675] 'agreement among raft nodes before linearized reading' (duration: 529.052314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:01.44812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:00.918933Z","time spent":"529.182986ms","remote":"127.0.0.1:48012","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-02-26T21:03:01.448218Z","caller":"traceutil/trace.go:171","msg":"trace[2000275228] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"821.596054ms","start":"2026-02-26T21:03:00.626616Z","end":"2026-02-26T21:03:01.448212Z","steps":["trace[2000275228] 'process raft request' (duration: 820.188199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:01.448273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:00.626602Z","time spent":"821.633084ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:01.44871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.700775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:01.448737Z","caller":"traceutil/trace.go:171","msg":"trace[1888767638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"359.762177ms","start":"2026-02-26T21:03:01.088965Z","end":"2026-02-26T21:03:01.448727Z","steps":["trace[1888767638] 'agreement among raft nodes before linearized reading' (duration: 359.134272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:01.448788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:01.088946Z","time spent":"359.836668ms","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:03:03.562289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518997370,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:03:03.928045Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.911759375s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:03:03.960839Z","caller":"traceutil/trace.go:171","msg":"trace[1070619583] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"1.944640491s","start":"2026-02-26T21:03:02.016177Z","end":"2026-02-26T21:03:03.960817Z","steps":["trace[1070619583] 'process raft request' (duration: 1.944484686s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:03.960972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:02.016152Z","time spent":"1.944755364s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:04.744349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.541044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:04.74444Z","caller":"traceutil/trace.go:171","msg":"trace[828927638] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"1.687717261s","start":"2026-02-26T21:03:03.056709Z","end":"2026-02-26T21:03:04.744426Z","steps":["trace[828927638] 'read index received' (duration: 872.671067ms)","trace[828927638] 'applied index is now lower than readState.Index' (duration: 815.044664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:04.744527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.687823293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:04.744521Z","caller":"traceutil/trace.go:171","msg":"trace[1413889955] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"2.727909504s","start":"2026-02-26T21:03:02.016581Z","end":"2026-02-26T21:03:04.744491Z","steps":["trace[1413889955] 'process raft request' (duration: 2.048156415s)","trace[1413889955] 'compare' (duration: 679.251338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:04.744614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.128328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:04.744646Z","caller":"traceutil/trace.go:171","msg":"trace[1503782372] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:861; }","duration":"418.20222ms","start":"2026-02-26T21:03:04.326435Z","end":"2026-02-26T21:03:04.744638Z","steps":["trace[1503782372] 'agreement among raft nodes before linearized reading' (duration: 418.155419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:04.744666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:04.326413Z","time spent":"418.248502ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:03:04.744664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288179562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T21:03:04.744671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:02.016557Z","time spent":"2.728036078s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:04.744693Z","caller":"traceutil/trace.go:171","msg":"trace[60821462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"1.288243855s","start":"2026-02-26T21:03:03.456442Z","end":"2026-02-26T21:03:04.744686Z","steps":["trace[60821462] 'agreement among raft nodes before linearized reading' (duration: 1.288137193s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:04.74472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:03.456422Z","time spent":"1.288292186s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:03:04.744553Z","caller":"traceutil/trace.go:171","msg":"trace[640424950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"1.687875415s","start":"2026-02-26T21:03:03.056668Z","end":"2026-02-26T21:03:04.744544Z","steps":["trace[640424950] 'agreement among raft nodes before linearized reading' (duration: 1.687832074s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:04.74481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:03.056653Z","time spent":"1.688150801s","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:03:04.744838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173562199s","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-02-26T21:03:04.744897Z","caller":"traceutil/trace.go:171","msg":"trace[2057936336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:861; }","duration":"1.173632652s","start":"2026-02-26T21:03:03.57125Z","end":"2026-02-26T21:03:04.744882Z","steps":["trace[2057936336] 'agreement among raft nodes before linearized reading' (duration: 1.173451898s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:04.744937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:03.571245Z","time spent":"1.173682693s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:03:04.744972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288172402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T21:03:04.745057Z","caller":"traceutil/trace.go:171","msg":"trace[1424078028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:861; }","duration":"1.288287914s","start":"2026-02-26T21:03:03.456747Z","end":"2026-02-26T21:03:04.745035Z","steps":["trace[1424078028] 'agreement among raft nodes before linearized reading' (duration: 1.288035668s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:04.745104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:03.45674Z","time spent":"1.288354827s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T21:03:05.006302Z","caller":"traceutil/trace.go:171","msg":"trace[482922093] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"257.365778ms","start":"2026-02-26T21:03:04.748649Z","end":"2026-02-26T21:03:05.006015Z","steps":["trace[482922093] 'process raft request' (duration: 257.179164ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:05.006982Z","caller":"traceutil/trace.go:171","msg":"trace[2037407317] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:898; }","duration":"255.978734ms","start":"2026-02-26T21:03:04.750978Z","end":"2026-02-26T21:03:05.006957Z","steps":["trace[2037407317] 'read index received' (duration: 254.839337ms)","trace[2037407317] 'applied index is now lower than readState.Index' (duration: 1.138797ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:05.007052Z","caller":"traceutil/trace.go:171","msg":"trace[414931854] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"258.337572ms","start":"2026-02-26T21:03:04.748675Z","end":"2026-02-26T21:03:05.007013Z","steps":["trace[414931854] 'process raft request' (duration: 258.155577ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:05.007103Z","caller":"traceutil/trace.go:171","msg":"trace[529041890] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"258.394693ms","start":"2026-02-26T21:03:04.748695Z","end":"2026-02-26T21:03:05.007089Z","steps":["trace[529041890] 'process raft request' (duration: 258.237229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:05.007139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.144098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:05.007162Z","caller":"traceutil/trace.go:171","msg":"trace[1149930970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"256.198609ms","start":"2026-02-26T21:03:04.750956Z","end":"2026-02-26T21:03:05.007155Z","steps":["trace[1149930970] 'agreement among raft nodes before linearized reading' (duration: 256.094367ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:16.961761Z","caller":"traceutil/trace.go:171","msg":"trace[105711905] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"166.368824ms","start":"2026-02-26T21:03:16.795376Z","end":"2026-02-26T21:03:16.961745Z","steps":["trace[105711905] 'process raft request' (duration: 166.263271ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:17.616936Z","caller":"traceutil/trace.go:171","msg":"trace[505698643] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"431.288191ms","start":"2026-02-26T21:03:17.185625Z","end":"2026-02-26T21:03:17.616913Z","steps":["trace[505698643] 'read index received' (duration: 431.105186ms)","trace[505698643] 'applied index is now lower than readState.Index' (duration: 182.185µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:17.617316Z","caller":"traceutil/trace.go:171","msg":"trace[721223114] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"505.463244ms","start":"2026-02-26T21:03:17.111839Z","end":"2026-02-26T21:03:17.617303Z","steps":["trace[721223114] 'process raft request' (duration: 504.959532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:17.617415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:17.111822Z","time spent":"505.529116ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:17.617641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.009779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T21:03:17.617765Z","caller":"traceutil/trace.go:171","msg":"trace[441401421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"432.154223ms","start":"2026-02-26T21:03:17.185597Z","end":"2026-02-26T21:03:17.617751Z","steps":["trace[441401421] 'agreement among raft nodes before linearized reading' (duration: 431.946798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:17.61783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:17.185583Z","time spent":"432.224834ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:17.617961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.842704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-26T21:03:17.618027Z","caller":"traceutil/trace.go:171","msg":"trace[6787735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"431.929236ms","start":"2026-02-26T21:03:17.186083Z","end":"2026-02-26T21:03:17.618012Z","steps":["trace[6787735] 'agreement among raft nodes before linearized reading' (duration: 431.738851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:17.61808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:17.186078Z","time spent":"431.995367ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T21:03:19.755343Z","caller":"traceutil/trace.go:171","msg":"trace[26051537] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"134.170606ms","start":"2026-02-26T21:03:19.621146Z","end":"2026-02-26T21:03:19.755317Z","steps":["trace[26051537] 'read index received' (duration: 37.512717ms)","trace[26051537] 'applied index is now lower than readState.Index' (duration: 96.656958ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:19.755645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.179532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-26T21:03:19.755664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.180312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-26T21:03:19.755699Z","caller":"traceutil/trace.go:171","msg":"trace[1278686917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"126.269124ms","start":"2026-02-26T21:03:19.629415Z","end":"2026-02-26T21:03:19.755684Z","steps":["trace[1278686917] 'agreement among raft nodes before linearized reading' (duration: 126.07549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:19.755697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.518064ms","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-02-26T21:03:19.755773Z","caller":"traceutil/trace.go:171","msg":"trace[161531878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"134.653407ms","start":"2026-02-26T21:03:19.621105Z","end":"2026-02-26T21:03:19.755758Z","steps":["trace[161531878] 'agreement among raft nodes before linearized reading' (duration: 134.390771ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:19.755699Z","caller":"traceutil/trace.go:171","msg":"trace[857304304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"126.230853ms","start":"2026-02-26T21:03:19.629459Z","end":"2026-02-26T21:03:19.755689Z","steps":["trace[857304304] 'agreement among raft nodes before linearized reading' (duration: 126.07735ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:19.755856Z","caller":"traceutil/trace.go:171","msg":"trace[117309204] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"211.294861ms","start":"2026-02-26T21:03:19.544165Z","end":"2026-02-26T21:03:19.75546Z","steps":["trace[117309204] 'process raft request' (duration: 114.472658ms)","trace[117309204] 'compare' (duration: 96.561337ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:20.125609Z","caller":"traceutil/trace.go:171","msg":"trace[540934237] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"366.603167ms","start":"2026-02-26T21:03:19.75898Z","end":"2026-02-26T21:03:20.125583Z","steps":["trace[540934237] 'process raft request' (duration: 301.329399ms)","trace[540934237] 'compare' (duration: 65.068603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:20.125728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:19.758954Z","time spent":"366.709839ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:20.128704Z","caller":"traceutil/trace.go:171","msg":"trace[1127255782] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:956; }","duration":"368.651207ms","start":"2026-02-26T21:03:19.760033Z","end":"2026-02-26T21:03:20.128684Z","steps":["trace[1127255782] 'read index received' (duration: 300.263893ms)","trace[1127255782] 'applied index is now lower than readState.Index' (duration: 68.386224ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:20.128832Z","caller":"traceutil/trace.go:171","msg":"trace[1007250713] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"367.472917ms","start":"2026-02-26T21:03:19.761343Z","end":"2026-02-26T21:03:20.128816Z","steps":["trace[1007250713] 'process raft request' (duration: 367.300893ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:20.128866Z","caller":"traceutil/trace.go:171","msg":"trace[879572020] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"368.883452ms","start":"2026-02-26T21:03:19.759919Z","end":"2026-02-26T21:03:20.128802Z","steps":["trace[879572020] 'process raft request' (duration: 368.606465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:20.128987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:19.76132Z","time spent":"367.55765ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:20.129149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.103898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-26T21:03:20.129223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.196892ms","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":"warn","ts":"2026-02-26T21:03:20.129265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.77435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5183"} {"level":"info","ts":"2026-02-26T21:03:20.129327Z","caller":"traceutil/trace.go:171","msg":"trace[1808361711] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:918; }","duration":"224.373447ms","start":"2026-02-26T21:03:19.904935Z","end":"2026-02-26T21:03:20.129308Z","steps":["trace[1808361711] 'agreement among raft nodes before linearized reading' (duration: 224.025298ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:20.129366Z","caller":"traceutil/trace.go:171","msg":"trace[365118503] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:918; }","duration":"201.923154ms","start":"2026-02-26T21:03:19.927424Z","end":"2026-02-26T21:03:20.129347Z","steps":["trace[365118503] 'agreement among raft nodes before linearized reading' (duration: 201.651957ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:20.129253Z","caller":"traceutil/trace.go:171","msg":"trace[939253367] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:918; }","duration":"369.2203ms","start":"2026-02-26T21:03:19.760019Z","end":"2026-02-26T21:03:20.12924Z","steps":["trace[939253367] 'agreement among raft nodes before linearized reading' (duration: 368.815931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:20.129568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:19.760015Z","time spent":"369.538848ms","remote":"127.0.0.1:48018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-26T21:03:20.129582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:19.759889Z","time spent":"369.055916ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:20.326464Z","caller":"traceutil/trace.go:171","msg":"trace[1822442039] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"187.876795ms","start":"2026-02-26T21:03:20.138555Z","end":"2026-02-26T21:03:20.326432Z","steps":["trace[1822442039] 'process raft request' (duration: 158.370031ms)","trace[1822442039] 'compare' (duration: 28.96718ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:20.326526Z","caller":"traceutil/trace.go:171","msg":"trace[343914471] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"186.554752ms","start":"2026-02-26T21:03:20.139942Z","end":"2026-02-26T21:03:20.326497Z","steps":["trace[343914471] 'process raft request' (duration: 186.272404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:20.698456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.673741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:20.698663Z","caller":"traceutil/trace.go:171","msg":"trace[1945178232] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:963; }","duration":"266.414783ms","start":"2026-02-26T21:03:20.432235Z","end":"2026-02-26T21:03:20.69865Z","steps":["trace[1945178232] 'read index received' (duration: 142.420444ms)","trace[1945178232] 'applied index is now lower than readState.Index' (duration: 123.993509ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:20.698693Z","caller":"traceutil/trace.go:171","msg":"trace[218121804] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"363.965372ms","start":"2026-02-26T21:03:20.334703Z","end":"2026-02-26T21:03:20.698668Z","steps":["trace[218121804] 'process raft request' (duration: 363.831499ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:20.698837Z","caller":"traceutil/trace.go:171","msg":"trace[553902555] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"360.990909ms","start":"2026-02-26T21:03:20.337838Z","end":"2026-02-26T21:03:20.698829Z","steps":["trace[553902555] 'process raft request' (duration: 360.768544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:20.698827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:20.334694Z","time spent":"364.066674ms","remote":"127.0.0.1:48124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:20.69889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:20.337822Z","time spent":"361.042941ms","remote":"127.0.0.1:48210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:20.699023Z","caller":"traceutil/trace.go:171","msg":"trace[1786989919] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"365.839088ms","start":"2026-02-26T21:03:20.33317Z","end":"2026-02-26T21:03:20.699009Z","steps":["trace[1786989919] 'process raft request' (duration: 241.535741ms)","trace[1786989919] 'compare' (duration: 123.441916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:20.699092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:20.333159Z","time spent":"365.90149ms","remote":"127.0.0.1:48018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:20.69904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.809904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:20.699366Z","caller":"traceutil/trace.go:171","msg":"trace[1599642452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"267.167263ms","start":"2026-02-26T21:03:20.432186Z","end":"2026-02-26T21:03:20.699353Z","steps":["trace[1599642452] 'agreement among raft nodes before linearized reading' (duration: 266.828635ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:22.458384Z","caller":"traceutil/trace.go:171","msg":"trace[978559300] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"329.313755ms","start":"2026-02-26T21:03:22.129049Z","end":"2026-02-26T21:03:22.458363Z","steps":["trace[978559300] 'read index received' (duration: 329.119859ms)","trace[978559300] 'applied index is now lower than readState.Index' (duration: 193.026µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:22.458696Z","caller":"traceutil/trace.go:171","msg":"trace[616110557] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"381.730922ms","start":"2026-02-26T21:03:22.076955Z","end":"2026-02-26T21:03:22.458686Z","steps":["trace[616110557] 'process raft request' (duration: 381.283212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:22.458804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:22.076945Z","time spent":"381.794235ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:22.458966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.579534ms","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-02-26T21:03:22.459032Z","caller":"traceutil/trace.go:171","msg":"trace[1496200050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"325.689806ms","start":"2026-02-26T21:03:22.133326Z","end":"2026-02-26T21:03:22.459016Z","steps":["trace[1496200050] 'agreement among raft nodes before linearized reading' (duration: 325.398859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:22.459073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:22.133317Z","time spent":"325.746378ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:03:22.459226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.191013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T21:03:22.459267Z","caller":"traceutil/trace.go:171","msg":"trace[1447422747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"272.248954ms","start":"2026-02-26T21:03:22.187008Z","end":"2026-02-26T21:03:22.459257Z","steps":["trace[1447422747] 'agreement among raft nodes before linearized reading' (duration: 272.120621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:22.459378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.952403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:03:22.459802Z","caller":"traceutil/trace.go:171","msg":"trace[1808615820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"326.376993ms","start":"2026-02-26T21:03:22.133405Z","end":"2026-02-26T21:03:22.459782Z","steps":["trace[1808615820] 'agreement among raft nodes before linearized reading' (duration: 325.909931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:22.459857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:22.133398Z","time spent":"326.445885ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:22.45938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.706445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:22.45997Z","caller":"traceutil/trace.go:171","msg":"trace[58934984] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:927; }","duration":"273.392082ms","start":"2026-02-26T21:03:22.186561Z","end":"2026-02-26T21:03:22.459953Z","steps":["trace[58934984] 'agreement among raft nodes before linearized reading' (duration: 272.727986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:22.459427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.37984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T21:03:22.460013Z","caller":"traceutil/trace.go:171","msg":"trace[289844736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"331.004685ms","start":"2026-02-26T21:03:22.128999Z","end":"2026-02-26T21:03:22.460003Z","steps":["trace[289844736] 'agreement among raft nodes before linearized reading' (duration: 330.284958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:22.460041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:22.128982Z","time spent":"331.052647ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:23.153684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.279116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:23.154215Z","caller":"traceutil/trace.go:171","msg":"trace[1269280688] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"689.980357ms","start":"2026-02-26T21:03:22.464214Z","end":"2026-02-26T21:03:23.154194Z","steps":["trace[1269280688] 'process raft request' (duration: 589.112577ms)","trace[1269280688] 'compare' (duration: 100.160623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:23.154307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:22.464187Z","time spent":"690.072858ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:23.410587Z","caller":"traceutil/trace.go:171","msg":"trace[2037298563] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"945.747471ms","start":"2026-02-26T21:03:22.464816Z","end":"2026-02-26T21:03:23.410564Z","steps":["trace[2037298563] 'process raft request' (duration: 945.67385ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:23.410594Z","caller":"traceutil/trace.go:171","msg":"trace[1278060109] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"946.034579ms","start":"2026-02-26T21:03:22.464518Z","end":"2026-02-26T21:03:23.410552Z","steps":["trace[1278060109] 'process raft request' (duration: 897.311592ms)","trace[1278060109] 'compare' (duration: 48.551603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:23.410743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:22.464797Z","time spent":"945.862055ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:23.410738Z","caller":"traceutil/trace.go:171","msg":"trace[749004284] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:968; }","duration":"374.857546ms","start":"2026-02-26T21:03:23.035852Z","end":"2026-02-26T21:03:23.41071Z","steps":["trace[749004284] 'read index received' (duration: 17.470492ms)","trace[749004284] 'applied index is now lower than readState.Index' (duration: 357.384524ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:23.410766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:22.464499Z","time spent":"946.188553ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:23.410822Z","caller":"traceutil/trace.go:171","msg":"trace[1970821504] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"946.019269ms","start":"2026-02-26T21:03:22.46478Z","end":"2026-02-26T21:03:23.410799Z","steps":["trace[1970821504] 'process raft request' (duration: 945.745842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:23.410921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:22.464761Z","time spent":"946.107042ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:23.410959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.270329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:03:23.410959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.168021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:23.410994Z","caller":"traceutil/trace.go:171","msg":"trace[1835172436] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:931; }","duration":"371.33806ms","start":"2026-02-26T21:03:23.039645Z","end":"2026-02-26T21:03:23.410983Z","steps":["trace[1835172436] 'agreement among raft nodes before linearized reading' (duration: 371.198127ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:23.411018Z","caller":"traceutil/trace.go:171","msg":"trace[1685119477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"360.254303ms","start":"2026-02-26T21:03:23.050747Z","end":"2026-02-26T21:03:23.411002Z","steps":["trace[1685119477] 'agreement among raft nodes before linearized reading' (duration: 360.159071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:23.411028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:23.039636Z","time spent":"371.383642ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":101,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:03:23.411073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:23.050734Z","time spent":"360.327844ms","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:03:23.411045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.102612ms","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-02-26T21:03:23.4113Z","caller":"traceutil/trace.go:171","msg":"trace[745073407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"375.467801ms","start":"2026-02-26T21:03:23.035814Z","end":"2026-02-26T21:03:23.411282Z","steps":["trace[745073407] 'agreement among raft nodes before linearized reading' (duration: 374.983069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:23.411355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:23.0358Z","time spent":"375.540812ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T21:03:23.600439Z","caller":"traceutil/trace.go:171","msg":"trace[1654208964] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"184.223735ms","start":"2026-02-26T21:03:23.416188Z","end":"2026-02-26T21:03:23.600412Z","steps":["trace[1654208964] 'process raft request' (duration: 107.797137ms)","trace[1654208964] 'compare' (duration: 76.315816ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:24.646104Z","caller":"traceutil/trace.go:171","msg":"trace[1858054278] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"335.480392ms","start":"2026-02-26T21:03:24.310601Z","end":"2026-02-26T21:03:24.646081Z","steps":["trace[1858054278] 'process raft request' (duration: 335.354809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:24.646244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.310583Z","time spent":"335.588556ms","remote":"127.0.0.1:47926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5735,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:24.646344Z","caller":"traceutil/trace.go:171","msg":"trace[1888742045] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:974; }","duration":"235.380953ms","start":"2026-02-26T21:03:24.410944Z","end":"2026-02-26T21:03:24.646325Z","steps":["trace[1888742045] 'read index received' (duration: 235.372483ms)","trace[1888742045] 'applied index is now lower than readState.Index' (duration: 7.25µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:24.646423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.481485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:24.646461Z","caller":"traceutil/trace.go:171","msg":"trace[1723234511] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:933; }","duration":"235.548427ms","start":"2026-02-26T21:03:24.410899Z","end":"2026-02-26T21:03:24.646447Z","steps":["trace[1723234511] 'agreement among raft nodes before linearized reading' (duration: 235.482675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:25.028701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.499757ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T21:03:25.028873Z","caller":"traceutil/trace.go:171","msg":"trace[86425040] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"218.352241ms","start":"2026-02-26T21:03:24.810504Z","end":"2026-02-26T21:03:25.028856Z","steps":["trace[86425040] 'read index received' (duration: 39.636159ms)","trace[86425040] 'applied index is now lower than readState.Index' (duration: 178.714302ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:25.028991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.456843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:25.029116Z","caller":"traceutil/trace.go:171","msg":"trace[1409778903] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:933; }","duration":"218.818822ms","start":"2026-02-26T21:03:24.810281Z","end":"2026-02-26T21:03:25.0291Z","steps":["trace[1409778903] 'agreement among raft nodes before linearized reading' (duration: 218.628647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:25.029137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.179119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:25.029198Z","caller":"traceutil/trace.go:171","msg":"trace[2135271873] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:933; }","duration":"154.266321ms","start":"2026-02-26T21:03:24.874916Z","end":"2026-02-26T21:03:25.029183Z","steps":["trace[2135271873] 'agreement among raft nodes before linearized reading' (duration: 154.155548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:25.029167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.744663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:25.029311Z","caller":"traceutil/trace.go:171","msg":"trace[1737140270] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:933; }","duration":"178.987528ms","start":"2026-02-26T21:03:24.850307Z","end":"2026-02-26T21:03:25.029295Z","steps":["trace[1737140270] 'agreement among raft nodes before linearized reading' (duration: 178.702582ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:25.26573Z","caller":"traceutil/trace.go:171","msg":"trace[1589567223] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"107.192624ms","start":"2026-02-26T21:03:25.158511Z","end":"2026-02-26T21:03:25.265703Z","steps":["trace[1589567223] 'read index received' (duration: 76.583443ms)","trace[1589567223] 'applied index is now lower than readState.Index' (duration: 30.608151ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:25.265828Z","caller":"traceutil/trace.go:171","msg":"trace[514410271] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"108.050844ms","start":"2026-02-26T21:03:25.157744Z","end":"2026-02-26T21:03:25.265795Z","steps":["trace[514410271] 'process raft request' (duration: 77.411753ms)","trace[514410271] 'compare' (duration: 30.397445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:25.265984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.4521ms","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-02-26T21:03:25.266027Z","caller":"traceutil/trace.go:171","msg":"trace[900437881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"107.511661ms","start":"2026-02-26T21:03:25.158504Z","end":"2026-02-26T21:03:25.266016Z","steps":["trace[900437881] 'agreement among raft nodes before linearized reading' (duration: 107.297396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:26.201857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.447929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:26.201991Z","caller":"traceutil/trace.go:171","msg":"trace[1551972726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"150.620402ms","start":"2026-02-26T21:03:26.051349Z","end":"2026-02-26T21:03:26.20197Z","steps":["trace[1551972726] 'range keys from in-memory index tree' (duration: 150.396237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:26.202232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.863709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-26T21:03:26.202481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.859911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-26T21:03:26.202509Z","caller":"traceutil/trace.go:171","msg":"trace[156770025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"598.175946ms","start":"2026-02-26T21:03:25.60431Z","end":"2026-02-26T21:03:26.202486Z","steps":["trace[156770025] 'range keys from in-memory index tree' (duration: 597.667934ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:26.202544Z","caller":"traceutil/trace.go:171","msg":"trace[1783031801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"785.144658ms","start":"2026-02-26T21:03:25.417377Z","end":"2026-02-26T21:03:26.202521Z","steps":["trace[1783031801] 'range keys from in-memory index tree' (duration: 784.709747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:26.202559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:25.60429Z","time spent":"598.259808ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:26.202238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.622148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T21:03:26.202672Z","caller":"traceutil/trace.go:171","msg":"trace[2060897541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:935; }","duration":"788.10234ms","start":"2026-02-26T21:03:25.41455Z","end":"2026-02-26T21:03:26.202652Z","steps":["trace[2060897541] 'range keys from in-memory index tree' (duration: 787.431133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:26.202246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.351068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-26T21:03:26.202734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:25.414533Z","time spent":"788.184931ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T21:03:26.202773Z","caller":"traceutil/trace.go:171","msg":"trace[330503528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"786.89967ms","start":"2026-02-26T21:03:25.415863Z","end":"2026-02-26T21:03:26.202762Z","steps":["trace[330503528] 'range keys from in-memory index tree' (duration: 786.254266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:26.202617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:25.417356Z","time spent":"785.246331ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:26.202861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:25.415853Z","time spent":"786.998173ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:03:27.020148Z","caller":"traceutil/trace.go:171","msg":"trace[1142801337] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"812.652913ms","start":"2026-02-26T21:03:26.207474Z","end":"2026-02-26T21:03:27.020127Z","steps":["trace[1142801337] 'process raft request' (duration: 812.51498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:27.02028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:26.207457Z","time spent":"812.759647ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:27.326624Z","caller":"traceutil/trace.go:171","msg":"trace[642754185] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:979; }","duration":"207.915088ms","start":"2026-02-26T21:03:27.118688Z","end":"2026-02-26T21:03:27.326603Z","steps":["trace[642754185] 'read index received' (duration: 207.618571ms)","trace[642754185] 'applied index is now lower than readState.Index' (duration: 295.677µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:27.327038Z","caller":"traceutil/trace.go:171","msg":"trace[622481536] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"1.119221478s","start":"2026-02-26T21:03:26.207804Z","end":"2026-02-26T21:03:27.327025Z","steps":["trace[622481536] 'process raft request' (duration: 1.118571982s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:27.327156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:26.207784Z","time spent":"1.119299241s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:27.327345Z","caller":"traceutil/trace.go:171","msg":"trace[696283781] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"1.119364592s","start":"2026-02-26T21:03:26.207971Z","end":"2026-02-26T21:03:27.327336Z","steps":["trace[696283781] 'process raft request' (duration: 1.118531031s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:27.327404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:26.207927Z","time spent":"1.119447405s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:27.32753Z","caller":"traceutil/trace.go:171","msg":"trace[1275616651] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"1.118905311s","start":"2026-02-26T21:03:26.208616Z","end":"2026-02-26T21:03:27.327521Z","steps":["trace[1275616651] 'process raft request' (duration: 1.117921427s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:27.327608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:26.208603Z","time spent":"1.118976833s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:27.32784Z","caller":"traceutil/trace.go:171","msg":"trace[1540469604] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"856.478244ms","start":"2026-02-26T21:03:26.471318Z","end":"2026-02-26T21:03:27.327796Z","steps":["trace[1540469604] 'process raft request' (duration: 855.252784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:27.32817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.479896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:03:27.328172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:26.471299Z","time spent":"856.72867ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:27.32822Z","caller":"traceutil/trace.go:171","msg":"trace[132220930] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:940; }","duration":"209.61001ms","start":"2026-02-26T21:03:27.118598Z","end":"2026-02-26T21:03:27.328208Z","steps":["trace[132220930] 'agreement among raft nodes before linearized reading' (duration: 209.540138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:27.328475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.10454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:27.328514Z","caller":"traceutil/trace.go:171","msg":"trace[1346393764] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:940; }","duration":"200.169991ms","start":"2026-02-26T21:03:27.128335Z","end":"2026-02-26T21:03:27.328505Z","steps":["trace[1346393764] 'agreement among raft nodes before linearized reading' (duration: 200.068908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:27.562728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.138643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:27.562841Z","caller":"traceutil/trace.go:171","msg":"trace[1889548657] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"225.531776ms","start":"2026-02-26T21:03:27.337281Z","end":"2026-02-26T21:03:27.562813Z","steps":["trace[1889548657] 'process raft request' (duration: 96.233099ms)","trace[1889548657] 'compare' (duration: 128.998489ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:28.094258Z","caller":"traceutil/trace.go:171","msg":"trace[1140475820] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"364.69862ms","start":"2026-02-26T21:03:27.72954Z","end":"2026-02-26T21:03:28.094239Z","steps":["trace[1140475820] 'process raft request' (duration: 364.556257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:28.094385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:27.72952Z","time spent":"364.796422ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:28.094593Z","caller":"traceutil/trace.go:171","msg":"trace[1609215716] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"342.085243ms","start":"2026-02-26T21:03:27.752473Z","end":"2026-02-26T21:03:28.094558Z","steps":["trace[1609215716] 'process raft request' (duration: 341.729934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:28.094735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:27.752449Z","time spent":"342.212677ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:03:28.574577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.495091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:28.574687Z","caller":"traceutil/trace.go:171","msg":"trace[1041237652] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"443.795773ms","start":"2026-02-26T21:03:28.130874Z","end":"2026-02-26T21:03:28.57467Z","steps":["trace[1041237652] 'read index received' (duration: 1.021086ms)","trace[1041237652] 'applied index is now lower than readState.Index' (duration: 442.773147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:28.574786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.915586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:28.574814Z","caller":"traceutil/trace.go:171","msg":"trace[1931520510] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:947; }","duration":"443.949687ms","start":"2026-02-26T21:03:28.130854Z","end":"2026-02-26T21:03:28.574804Z","steps":["trace[1931520510] 'agreement among raft nodes before linearized reading' (duration: 443.860125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:28.574852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:28.130801Z","time spent":"444.039819ms","remote":"127.0.0.1:48056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:03:28.574901Z","caller":"traceutil/trace.go:171","msg":"trace[163953236] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"476.428112ms","start":"2026-02-26T21:03:28.098428Z","end":"2026-02-26T21:03:28.574856Z","steps":["trace[163953236] 'process raft request' (duration: 33.579272ms)","trace[163953236] 'compare' (duration: 442.326587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:28.575041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:28.098412Z","time spent":"476.562495ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:03:29.805041Z","caller":"traceutil/trace.go:171","msg":"trace[1304138706] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"472.781374ms","start":"2026-02-26T21:03:29.332241Z","end":"2026-02-26T21:03:29.805022Z","steps":["trace[1304138706] 'read index received' (duration: 472.61716ms)","trace[1304138706] 'applied index is now lower than readState.Index' (duration: 163.584µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:29.805118Z","caller":"traceutil/trace.go:171","msg":"trace[1026066254] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"778.205811ms","start":"2026-02-26T21:03:29.026882Z","end":"2026-02-26T21:03:29.805088Z","steps":["trace[1026066254] 'process raft request' (duration: 778.025657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:29.805207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.952598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:03:29.805234Z","caller":"traceutil/trace.go:171","msg":"trace[559373697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"473.01861ms","start":"2026-02-26T21:03:29.332206Z","end":"2026-02-26T21:03:29.805224Z","steps":["trace[559373697] 'agreement among raft nodes before linearized reading' (duration: 472.915708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:29.805256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.332189Z","time spent":"473.061431ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:29.805279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.026871Z","time spent":"778.315053ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:29.805387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.320383ms","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-02-26T21:03:29.805431Z","caller":"traceutil/trace.go:171","msg":"trace[849078803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"472.380064ms","start":"2026-02-26T21:03:29.333039Z","end":"2026-02-26T21:03:29.805419Z","steps":["trace[849078803] 'agreement among raft nodes before linearized reading' (duration: 472.253011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:29.805463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.333033Z","time spent":"472.423255ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:03:29.805534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.018257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-26T21:03:29.805546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.294987ms","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-02-26T21:03:29.805571Z","caller":"traceutil/trace.go:171","msg":"trace[362564958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"239.360899ms","start":"2026-02-26T21:03:29.566203Z","end":"2026-02-26T21:03:29.805564Z","steps":["trace[362564958] 'agreement among raft nodes before linearized reading' (duration: 239.299118ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:29.805573Z","caller":"traceutil/trace.go:171","msg":"trace[1942719757] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:948; }","duration":"329.053718ms","start":"2026-02-26T21:03:29.476507Z","end":"2026-02-26T21:03:29.80556Z","steps":["trace[1942719757] 'agreement among raft nodes before linearized reading' (duration: 328.916695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:29.805602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.47645Z","time spent":"329.14522ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-02-26T21:03:29.805645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.505257ms","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-02-26T21:03:29.805677Z","caller":"traceutil/trace.go:171","msg":"trace[1014296803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"472.568389ms","start":"2026-02-26T21:03:29.333097Z","end":"2026-02-26T21:03:29.805666Z","steps":["trace[1014296803] 'agreement among raft nodes before linearized reading' (duration: 472.485557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:29.805705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.333058Z","time spent":"472.640061ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:30.552753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101365518997588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:03:30.623766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.806715Z","time spent":"817.046519ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T21:03:31.245842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.34225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:31.246147Z","caller":"traceutil/trace.go:171","msg":"trace[1945176452] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.436515491s","start":"2026-02-26T21:03:29.809601Z","end":"2026-02-26T21:03:31.246116Z","steps":["trace[1945176452] 'process raft request' (duration: 1.143490195s)","trace[1945176452] 'compare' (duration: 291.633743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:31.246278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.809581Z","time spent":"1.436643325s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:31.246716Z","caller":"traceutil/trace.go:171","msg":"trace[282723049] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"1.43645471s","start":"2026-02-26T21:03:29.810242Z","end":"2026-02-26T21:03:31.246697Z","steps":["trace[282723049] 'process raft request' (duration: 1.435746052s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:31.246758Z","caller":"traceutil/trace.go:171","msg":"trace[19021810] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"1.436286366s","start":"2026-02-26T21:03:29.810453Z","end":"2026-02-26T21:03:31.24674Z","steps":["trace[19021810] 'process raft request' (duration: 1.436197204s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:31.246811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.810233Z","time spent":"1.436530183s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:31.246934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.810443Z","time spent":"1.436356959s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:31.247337Z","caller":"traceutil/trace.go:171","msg":"trace[1106877674] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"622.383702ms","start":"2026-02-26T21:03:30.624937Z","end":"2026-02-26T21:03:31.247321Z","steps":["trace[1106877674] 'process raft request' (duration: 622.29816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:31.247493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:30.624916Z","time spent":"622.520326ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:31.247551Z","caller":"traceutil/trace.go:171","msg":"trace[1184876977] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"1.437095126s","start":"2026-02-26T21:03:29.810439Z","end":"2026-02-26T21:03:31.247534Z","steps":["trace[1184876977] 'process raft request' (duration: 1.436266356s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:31.247608Z","caller":"traceutil/trace.go:171","msg":"trace[522583307] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:991; }","duration":"1.195820701s","start":"2026-02-26T21:03:30.051771Z","end":"2026-02-26T21:03:31.247592Z","steps":["trace[522583307] 'read index received' (duration: 562.423582ms)","trace[522583307] 'applied index is now lower than readState.Index' (duration: 633.395879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:31.247641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:29.810405Z","time spent":"1.437189159s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:31.247689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195902572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:31.247722Z","caller":"traceutil/trace.go:171","msg":"trace[911548494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"1.195998945s","start":"2026-02-26T21:03:30.051711Z","end":"2026-02-26T21:03:31.24771Z","steps":["trace[911548494] 'agreement among raft nodes before linearized reading' (duration: 1.195932224s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:31.247759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:30.051689Z","time spent":"1.196059746s","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:03:32.13808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.381631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-26T21:03:32.13814Z","caller":"traceutil/trace.go:171","msg":"trace[1400694566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"328.485774ms","start":"2026-02-26T21:03:31.80964Z","end":"2026-02-26T21:03:32.138126Z","steps":["trace[1400694566] 'range keys from in-memory index tree' (duration: 328.215537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:32.138182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:31.809612Z","time spent":"328.547956ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T21:03:32.280453Z","caller":"traceutil/trace.go:171","msg":"trace[483587944] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"139.118525ms","start":"2026-02-26T21:03:32.141311Z","end":"2026-02-26T21:03:32.28043Z","steps":["trace[483587944] 'process raft request' (duration: 138.92101ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:33.002922Z","caller":"traceutil/trace.go:171","msg":"trace[407701049] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"248.416158ms","start":"2026-02-26T21:03:32.754467Z","end":"2026-02-26T21:03:33.002883Z","steps":["trace[407701049] 'process raft request' (duration: 246.634365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:36.626865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.938203165s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T21:03:36.626965Z","caller":"traceutil/trace.go:171","msg":"trace[1946681792] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"1.575157755s","start":"2026-02-26T21:03:35.05179Z","end":"2026-02-26T21:03:36.626947Z","steps":["trace[1946681792] 'read index received' (duration: 53.011µs)","trace[1946681792] 'applied index is now lower than readState.Index' (duration: 1.575103294s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:36.627374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.575568965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:36.627439Z","caller":"traceutil/trace.go:171","msg":"trace[2028850154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"1.575673657s","start":"2026-02-26T21:03:35.051746Z","end":"2026-02-26T21:03:36.62742Z","steps":["trace[2028850154] 'agreement among raft nodes before linearized reading' (duration: 1.575567565s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:36.62813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:35.051721Z","time spent":"1.576390176s","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T21:03:36.628306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366401997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T21:03:36.628418Z","caller":"traceutil/trace.go:171","msg":"trace[1462395000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"1.36650002s","start":"2026-02-26T21:03:35.261857Z","end":"2026-02-26T21:03:36.628357Z","steps":["trace[1462395000] 'agreement among raft nodes before linearized reading' (duration: 1.365499175s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:36.62849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:35.261841Z","time spent":"1.366633242s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:03:36.627732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363540048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T21:03:36.628592Z","caller":"traceutil/trace.go:171","msg":"trace[1930899503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"1.364418789s","start":"2026-02-26T21:03:35.264152Z","end":"2026-02-26T21:03:36.628571Z","steps":["trace[1930899503] 'agreement among raft nodes before linearized reading' (duration: 1.363373903s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:36.627769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364938942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-26T21:03:36.628661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:35.264148Z","time spent":"1.36448697s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:03:36.62785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36487775s","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-02-26T21:03:36.628795Z","caller":"traceutil/trace.go:171","msg":"trace[1714077056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"1.365824933s","start":"2026-02-26T21:03:35.262954Z","end":"2026-02-26T21:03:36.628779Z","steps":["trace[1714077056] 'agreement among raft nodes before linearized reading' (duration: 1.364580702s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:36.62787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.405342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T21:03:36.628935Z","caller":"traceutil/trace.go:171","msg":"trace[280664175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"336.515619ms","start":"2026-02-26T21:03:36.292398Z","end":"2026-02-26T21:03:36.628914Z","steps":["trace[280664175] 'agreement among raft nodes before linearized reading' (duration: 335.140925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:36.6289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:35.262948Z","time spent":"1.365936475s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:03:36.629006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:36.292369Z","time spent":"336.616722ms","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T21:03:36.628713Z","caller":"traceutil/trace.go:171","msg":"trace[1335437656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"1.365917646s","start":"2026-02-26T21:03:35.262777Z","end":"2026-02-26T21:03:36.628695Z","steps":["trace[1335437656] 'agreement among raft nodes before linearized reading' (duration: 1.364791998s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:36.629236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:35.262773Z","time spent":"1.366440608s","remote":"127.0.0.1:48012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T21:03:36.772726Z","caller":"traceutil/trace.go:171","msg":"trace[183083503] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"138.759115ms","start":"2026-02-26T21:03:36.633942Z","end":"2026-02-26T21:03:36.772701Z","steps":["trace[183083503] 'process raft request' (duration: 138.655923ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:36.77284Z","caller":"traceutil/trace.go:171","msg":"trace[1890060899] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1013; }","duration":"136.370908ms","start":"2026-02-26T21:03:36.636451Z","end":"2026-02-26T21:03:36.772822Z","steps":["trace[1890060899] 'read index received' (duration: 20.176088ms)","trace[1890060899] 'applied index is now lower than readState.Index' (duration: 116.19408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:36.772937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.47252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:36.772963Z","caller":"traceutil/trace.go:171","msg":"trace[1430983807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"136.525531ms","start":"2026-02-26T21:03:36.636431Z","end":"2026-02-26T21:03:36.772957Z","steps":["trace[1430983807] 'agreement among raft nodes before linearized reading' (duration: 136.46048ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:36.773062Z","caller":"traceutil/trace.go:171","msg":"trace[1306304632] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"138.282584ms","start":"2026-02-26T21:03:36.63475Z","end":"2026-02-26T21:03:36.773032Z","steps":["trace[1306304632] 'process raft request' (duration: 137.912685ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:36.772744Z","caller":"traceutil/trace.go:171","msg":"trace[411035037] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"138.827507ms","start":"2026-02-26T21:03:36.633887Z","end":"2026-02-26T21:03:36.772714Z","steps":["trace[411035037] 'process raft request' (duration: 104.242571ms)","trace[411035037] 'compare' (duration: 34.236578ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:36.773118Z","caller":"traceutil/trace.go:171","msg":"trace[2113349185] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"138.358666ms","start":"2026-02-26T21:03:36.634751Z","end":"2026-02-26T21:03:36.77311Z","steps":["trace[2113349185] 'process raft request' (duration: 138.013008ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:38.51804Z","caller":"traceutil/trace.go:171","msg":"trace[104015939] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"227.455851ms","start":"2026-02-26T21:03:38.290559Z","end":"2026-02-26T21:03:38.518015Z","steps":["trace[104015939] 'read index received' (duration: 149.416884ms)","trace[104015939] 'applied index is now lower than readState.Index' (duration: 78.037987ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:38.518167Z","caller":"traceutil/trace.go:171","msg":"trace[795965302] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"251.057851ms","start":"2026-02-26T21:03:38.26707Z","end":"2026-02-26T21:03:38.518128Z","steps":["trace[795965302] 'process raft request' (duration: 173.002104ms)","trace[795965302] 'compare' (duration: 77.800101ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:38.518405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.819159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:38.518487Z","caller":"traceutil/trace.go:171","msg":"trace[2071980889] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:979; }","duration":"227.942603ms","start":"2026-02-26T21:03:38.290528Z","end":"2026-02-26T21:03:38.51847Z","steps":["trace[2071980889] 'agreement among raft nodes before linearized reading' (duration: 227.646406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:52.998173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.83356ms","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-02-26T21:03:52.998264Z","caller":"traceutil/trace.go:171","msg":"trace[1002583488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"129.973312ms","start":"2026-02-26T21:03:52.868272Z","end":"2026-02-26T21:03:52.998245Z","steps":["trace[1002583488] 'range keys from in-memory index tree' (duration: 129.660535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:52.998372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.226118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T21:03:52.998487Z","caller":"traceutil/trace.go:171","msg":"trace[1910819571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"101.413022ms","start":"2026-02-26T21:03:52.897044Z","end":"2026-02-26T21:03:52.998457Z","steps":["trace[1910819571] 'range keys from in-memory index tree' (duration: 100.920081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:53.255773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.471293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-26T21:03:53.255889Z","caller":"traceutil/trace.go:171","msg":"trace[442828547] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1060; }","duration":"103.678407ms","start":"2026-02-26T21:03:53.152182Z","end":"2026-02-26T21:03:53.25586Z","steps":["trace[442828547] 'range keys from in-memory index tree' (duration: 103.243037ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:53.683665Z","caller":"traceutil/trace.go:171","msg":"trace[1007298596] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"104.530238ms","start":"2026-02-26T21:03:53.579111Z","end":"2026-02-26T21:03:53.683641Z","steps":["trace[1007298596] 'process raft request' (duration: 104.382565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:05:34.826855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.295235ms","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-02-26T21:05:34.826998Z","caller":"traceutil/trace.go:171","msg":"trace[1674104196] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1475; }","duration":"172.51455ms","start":"2026-02-26T21:05:34.654464Z","end":"2026-02-26T21:05:34.826978Z","steps":["trace[1674104196] 'count revisions from in-memory index tree' (duration: 172.201423ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:59.634605Z","caller":"traceutil/trace.go:171","msg":"trace[2344153] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"132.868101ms","start":"2026-02-26T21:05:59.501698Z","end":"2026-02-26T21:05:59.634566Z","steps":["trace[2344153] 'process raft request' (duration: 127.030942ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:06:51.282669Z","caller":"traceutil/trace.go:171","msg":"trace[1687095862] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"280.669215ms","start":"2026-02-26T21:06:51.001963Z","end":"2026-02-26T21:06:51.282632Z","steps":["trace[1687095862] 'process raft request' (duration: 280.44766ms)"],"step_count":1}