{"level":"warn","ts":"2026-04-28T03:15:07.017778Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T03:15:07.017861Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T03:15:07.017918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T03:15:07.017931Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-28T03:15:07.017947Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T03:15:07.018576Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-04-28T03:15:07.018645Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T03:15:07.139637Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"79.275655ms"} {"level":"info","ts":"2026-04-28T03:15:07.427633Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-04-28T03:15:07.427774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T03:15:07.427844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-04-28T03:15:07.427869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T03:15:07.42789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-04-28T03:15:07.427956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-04-28T03:15:08.661515Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T03:15:08.866128Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T03:15:08.955143Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T03:15:08.959509Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T03:15:08.959678Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T03:15:08.959933Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T03:15:08.960004Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T03:15:08.960035Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T03:15:08.966112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-04-28T03:15:08.966331Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-28T03:15:08.966769Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T03:15:08.966987Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T03:15:08.967017Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T03:15:08.967121Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-28T03:15:08.967135Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-28T03:15:09.441941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T03:15:09.441991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T03:15:09.44201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-04-28T03:15:09.442034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-04-28T03:15:09.442043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-28T03:15:09.442071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-04-28T03:15:09.442081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-28T03:15:09.443062Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T03:15:09.443169Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:15:09.443494Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:15:09.44369Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:15:09.443908Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T03:15:09.443925Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T03:15:09.445617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T03:15:09.447462Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-04-28T03:15:09.447693Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:15:09.447819Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:15:09.447852Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:15:37.527113Z","caller":"traceutil/trace.go:171","msg":"trace[559775168] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"817.246617ms","start":"2026-04-28T03:15:36.709841Z","end":"2026-04-28T03:15:37.527088Z","steps":["trace[559775168] 'process raft request' (duration: 817.106302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.527667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.709817Z","time spent":"817.390203ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.688687Z","caller":"traceutil/trace.go:171","msg":"trace[718626743] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"970.222296ms","start":"2026-04-28T03:15:36.718423Z","end":"2026-04-28T03:15:37.688645Z","steps":["trace[718626743] 'process raft request' (duration: 970.039877ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.688764Z","caller":"traceutil/trace.go:171","msg":"trace[571152468] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"968.623584ms","start":"2026-04-28T03:15:36.720113Z","end":"2026-04-28T03:15:37.688737Z","steps":["trace[571152468] 'process raft request' (duration: 968.461417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.688902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.720103Z","time spent":"968.737206ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:15:37.688893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.718401Z","time spent":"970.373392ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.689907Z","caller":"traceutil/trace.go:171","msg":"trace[1950323578] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"963.618098ms","start":"2026-04-28T03:15:36.726274Z","end":"2026-04-28T03:15:37.689892Z","steps":["trace[1950323578] 'process raft request' (duration: 962.392646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.689978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.726257Z","time spent":"963.692155ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.690353Z","caller":"traceutil/trace.go:171","msg":"trace[1089458739] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"959.499404ms","start":"2026-04-28T03:15:36.730843Z","end":"2026-04-28T03:15:37.690342Z","steps":["trace[1089458739] 'process raft request' (duration: 958.789588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.69041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.730832Z","time spent":"959.555051ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.691134Z","caller":"traceutil/trace.go:171","msg":"trace[381298752] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:473; }","duration":"567.35774ms","start":"2026-04-28T03:15:37.123764Z","end":"2026-04-28T03:15:37.691122Z","steps":["trace[381298752] 'read index received' (duration: 403.675142ms)","trace[381298752] 'applied index is now lower than readState.Index' (duration: 163.681798ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:15:37.69129Z","caller":"traceutil/trace.go:171","msg":"trace[2027673068] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"960.39362ms","start":"2026-04-28T03:15:36.730888Z","end":"2026-04-28T03:15:37.691281Z","steps":["trace[2027673068] 'process raft request' (duration: 959.409755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.691343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.730884Z","time spent":"960.435324ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.691465Z","caller":"traceutil/trace.go:171","msg":"trace[286153204] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"960.474319ms","start":"2026-04-28T03:15:36.730982Z","end":"2026-04-28T03:15:37.691456Z","steps":["trace[286153204] 'process raft request' (duration: 959.675994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.69151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.730978Z","time spent":"960.514863ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.691615Z","caller":"traceutil/trace.go:171","msg":"trace[1200413365] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"955.771984ms","start":"2026-04-28T03:15:36.735835Z","end":"2026-04-28T03:15:37.691607Z","steps":["trace[1200413365] 'process raft request' (duration: 954.898701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.691662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.735826Z","time spent":"955.815069ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.69177Z","caller":"traceutil/trace.go:171","msg":"trace[1079217995] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"951.742092ms","start":"2026-04-28T03:15:36.740021Z","end":"2026-04-28T03:15:37.691763Z","steps":["trace[1079217995] 'process raft request' (duration: 950.846025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.691865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.740013Z","time spent":"951.830882ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.691982Z","caller":"traceutil/trace.go:171","msg":"trace[1758335115] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"924.002473ms","start":"2026-04-28T03:15:36.767967Z","end":"2026-04-28T03:15:37.69197Z","steps":["trace[1758335115] 'process raft request' (duration: 922.944989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.69203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.767948Z","time spent":"924.06303ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.692184Z","caller":"traceutil/trace.go:171","msg":"trace[253123472] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"905.949035ms","start":"2026-04-28T03:15:36.78622Z","end":"2026-04-28T03:15:37.692169Z","steps":["trace[253123472] 'process raft request' (duration: 904.754346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.692263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.786194Z","time spent":"906.035994ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:37.692442Z","caller":"traceutil/trace.go:171","msg":"trace[1907509115] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"800.923595ms","start":"2026-04-28T03:15:36.891505Z","end":"2026-04-28T03:15:37.692428Z","steps":["trace[1907509115] 'process raft request' (duration: 799.563939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.692508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:36.891477Z","time spent":"801.001904ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:15:37.692756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.976105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T03:15:37.692826Z","caller":"traceutil/trace.go:171","msg":"trace[842609384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:471; }","duration":"569.088887ms","start":"2026-04-28T03:15:37.123724Z","end":"2026-04-28T03:15:37.692813Z","steps":["trace[842609384] 'agreement among raft nodes before linearized reading' (duration: 568.92161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.69286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:37.123705Z","time spent":"569.146474ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:15:37.985774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.973237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:15:37.985992Z","caller":"traceutil/trace.go:171","msg":"trace[1068511311] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"451.627473ms","start":"2026-04-28T03:15:37.534324Z","end":"2026-04-28T03:15:37.985951Z","steps":["trace[1068511311] 'process raft request' (duration: 286.268314ms)","trace[1068511311] 'compare' (duration: 164.779836ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:15:37.986012Z","caller":"traceutil/trace.go:171","msg":"trace[1824194703] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"449.793326ms","start":"2026-04-28T03:15:37.536195Z","end":"2026-04-28T03:15:37.985989Z","steps":["trace[1824194703] 'process raft request' (duration: 449.687954ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.986078Z","caller":"traceutil/trace.go:171","msg":"trace[1797804914] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:484; }","duration":"294.88859ms","start":"2026-04-28T03:15:37.691173Z","end":"2026-04-28T03:15:37.986062Z","steps":["trace[1797804914] 'read index received' (duration: 129.483337ms)","trace[1797804914] 'applied index is now lower than readState.Index' (duration: 165.403933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:15:37.986147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:37.534298Z","time spent":"451.777659ms","remote":"127.0.0.1:47346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:15:37.98634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.559863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T03:15:37.986352Z","caller":"traceutil/trace.go:171","msg":"trace[1000416605] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"292.74875ms","start":"2026-04-28T03:15:37.693592Z","end":"2026-04-28T03:15:37.986341Z","steps":["trace[1000416605] 'process raft request' (duration: 292.363388ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.986373Z","caller":"traceutil/trace.go:171","msg":"trace[1910064469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"590.616729ms","start":"2026-04-28T03:15:37.395745Z","end":"2026-04-28T03:15:37.986362Z","steps":["trace[1910064469] 'agreement among raft nodes before linearized reading' (duration: 590.410067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.986382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.210305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T03:15:37.986485Z","caller":"traceutil/trace.go:171","msg":"trace[1063466974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"594.359031ms","start":"2026-04-28T03:15:37.392106Z","end":"2026-04-28T03:15:37.986465Z","steps":["trace[1063466974] 'agreement among raft nodes before linearized reading' (duration: 594.06547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:37.986529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:37.392089Z","time spent":"594.431699ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T03:15:37.986561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:37.536178Z","time spent":"449.9194ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:15:37.986403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:37.395736Z","time spent":"590.660064ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T03:15:37.986734Z","caller":"traceutil/trace.go:171","msg":"trace[1454033352] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"276.351939ms","start":"2026-04-28T03:15:37.710372Z","end":"2026-04-28T03:15:37.986724Z","steps":["trace[1454033352] 'process raft request' (duration: 276.26649ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.986831Z","caller":"traceutil/trace.go:171","msg":"trace[918154865] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"279.178873ms","start":"2026-04-28T03:15:37.70763Z","end":"2026-04-28T03:15:37.986809Z","steps":["trace[918154865] 'process raft request' (duration: 278.932496ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.986873Z","caller":"traceutil/trace.go:171","msg":"trace[1432756619] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"284.344217ms","start":"2026-04-28T03:15:37.702507Z","end":"2026-04-28T03:15:37.986851Z","steps":["trace[1432756619] 'process raft request' (duration: 283.706269ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.986936Z","caller":"traceutil/trace.go:171","msg":"trace[1594346837] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"282.806622ms","start":"2026-04-28T03:15:37.704115Z","end":"2026-04-28T03:15:37.986921Z","steps":["trace[1594346837] 'process raft request' (duration: 282.366305ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.986947Z","caller":"traceutil/trace.go:171","msg":"trace[1362117854] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"276.749742ms","start":"2026-04-28T03:15:37.710188Z","end":"2026-04-28T03:15:37.986938Z","steps":["trace[1362117854] 'process raft request' (duration: 276.417706ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.987113Z","caller":"traceutil/trace.go:171","msg":"trace[306423551] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"276.430408ms","start":"2026-04-28T03:15:37.710666Z","end":"2026-04-28T03:15:37.987097Z","steps":["trace[306423551] 'process raft request' (duration: 276.011503ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.987381Z","caller":"traceutil/trace.go:171","msg":"trace[1789983364] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"269.38352ms","start":"2026-04-28T03:15:37.717987Z","end":"2026-04-28T03:15:37.98737Z","steps":["trace[1789983364] 'process raft request' (duration: 269.306102ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:37.987411Z","caller":"traceutil/trace.go:171","msg":"trace[1244205457] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"275.675815ms","start":"2026-04-28T03:15:37.711716Z","end":"2026-04-28T03:15:37.987392Z","steps":["trace[1244205457] 'process raft request' (duration: 275.309696ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:38.012729Z","caller":"traceutil/trace.go:171","msg":"trace[2103418717] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"273.528155ms","start":"2026-04-28T03:15:37.739172Z","end":"2026-04-28T03:15:38.0127Z","steps":["trace[2103418717] 'process raft request' (duration: 272.391113ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:38.01293Z","caller":"traceutil/trace.go:171","msg":"trace[1497111778] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"254.408332ms","start":"2026-04-28T03:15:37.758515Z","end":"2026-04-28T03:15:38.012923Z","steps":["trace[1497111778] 'process raft request' (duration: 253.327876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:38.029737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.554383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:15:38.029824Z","caller":"traceutil/trace.go:171","msg":"trace[926584271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:484; }","duration":"129.678066ms","start":"2026-04-28T03:15:37.900123Z","end":"2026-04-28T03:15:38.029801Z","steps":["trace[926584271] 'agreement among raft nodes before linearized reading' (duration: 111.865583ms)","trace[926584271] 'range keys from in-memory index tree' (duration: 17.69345ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:15:40.768613Z","caller":"traceutil/trace.go:171","msg":"trace[1905024347] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"139.492221ms","start":"2026-04-28T03:15:40.629092Z","end":"2026-04-28T03:15:40.768584Z","steps":["trace[1905024347] 'process raft request' (duration: 139.29852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:41.443582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.906809ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:15:41.444201Z","caller":"traceutil/trace.go:171","msg":"trace[1215742176] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:545; }","duration":"227.041743ms","start":"2026-04-28T03:15:41.216643Z","end":"2026-04-28T03:15:41.443685Z","steps":["trace[1215742176] 'range keys from in-memory index tree' (duration: 226.891277ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:41.492282Z","caller":"traceutil/trace.go:171","msg":"trace[540332562] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"313.547004ms","start":"2026-04-28T03:15:41.178707Z","end":"2026-04-28T03:15:41.492254Z","steps":["trace[540332562] 'process raft request' (duration: 313.007746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:41.492414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:15:41.178682Z","time spent":"313.677057ms","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:15:42.699293Z","caller":"traceutil/trace.go:171","msg":"trace[1852036166] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"185.699163ms","start":"2026-04-28T03:15:42.513573Z","end":"2026-04-28T03:15:42.699272Z","steps":["trace[1852036166] 'process raft request' (duration: 176.379662ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:43.975655Z","caller":"traceutil/trace.go:171","msg":"trace[1688543939] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"263.368424ms","start":"2026-04-28T03:15:43.712244Z","end":"2026-04-28T03:15:43.975612Z","steps":["trace[1688543939] 'process raft request' (duration: 262.783622ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:46.234385Z","caller":"traceutil/trace.go:171","msg":"trace[938392221] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"268.183366ms","start":"2026-04-28T03:15:45.966149Z","end":"2026-04-28T03:15:46.234332Z","steps":["trace[938392221] 'process raft request' (duration: 180.238636ms)","trace[938392221] 'compare' (duration: 87.677755ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:15:46.234636Z","caller":"traceutil/trace.go:171","msg":"trace[2038894269] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"199.792585ms","start":"2026-04-28T03:15:46.034457Z","end":"2026-04-28T03:15:46.23425Z","steps":["trace[2038894269] 'read index received' (duration: 111.897105ms)","trace[2038894269] 'applied index is now lower than readState.Index' (duration: 87.89377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:15:46.235215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.748066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T03:15:46.235317Z","caller":"traceutil/trace.go:171","msg":"trace[446510880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"200.887679ms","start":"2026-04-28T03:15:46.034374Z","end":"2026-04-28T03:15:46.235262Z","steps":["trace[446510880] 'agreement among raft nodes before linearized reading' (duration: 200.422069ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:46.399026Z","caller":"traceutil/trace.go:171","msg":"trace[1677835594] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"159.092072ms","start":"2026-04-28T03:15:46.239895Z","end":"2026-04-28T03:15:46.398987Z","steps":["trace[1677835594] 'process raft request' (duration: 137.377187ms)","trace[1677835594] 'compare' (duration: 21.428619ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:15:46.399066Z","caller":"traceutil/trace.go:171","msg":"trace[1520493363] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"159.040971ms","start":"2026-04-28T03:15:46.239996Z","end":"2026-04-28T03:15:46.399037Z","steps":["trace[1520493363] 'process raft request' (duration: 158.856887ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:46.399432Z","caller":"traceutil/trace.go:171","msg":"trace[328126920] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"159.365499ms","start":"2026-04-28T03:15:46.240014Z","end":"2026-04-28T03:15:46.399379Z","steps":["trace[328126920] 'process raft request' (duration: 158.899247ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:46.399634Z","caller":"traceutil/trace.go:171","msg":"trace[834882264] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"159.202895ms","start":"2026-04-28T03:15:46.240337Z","end":"2026-04-28T03:15:46.39954Z","steps":["trace[834882264] 'process raft request' (duration: 158.926499ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:15:51.733476Z","caller":"traceutil/trace.go:171","msg":"trace[136793020] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"111.293412ms","start":"2026-04-28T03:15:51.622147Z","end":"2026-04-28T03:15:51.73344Z","steps":["trace[136793020] 'process raft request' (duration: 110.881873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:15:58.43023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.388078ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T03:15:58.430786Z","caller":"traceutil/trace.go:171","msg":"trace[1826706958] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"286.71566ms","start":"2026-04-28T03:15:58.144035Z","end":"2026-04-28T03:15:58.430751Z","steps":["trace[1826706958] 'process raft request' (duration: 286.309411ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:00.962516Z","caller":"traceutil/trace.go:171","msg":"trace[801175811] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"431.248168ms","start":"2026-04-28T03:16:00.531236Z","end":"2026-04-28T03:16:00.962484Z","steps":["trace[801175811] 'read index received' (duration: 431.089734ms)","trace[801175811] 'applied index is now lower than readState.Index' (duration: 157.354µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:00.962828Z","caller":"traceutil/trace.go:171","msg":"trace[439740311] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"465.136127ms","start":"2026-04-28T03:16:00.497677Z","end":"2026-04-28T03:16:00.962814Z","steps":["trace[439740311] 'process raft request' (duration: 464.630116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:00.962967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:00.497654Z","time spent":"465.213728ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:16:00.963369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.125937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T03:16:00.963416Z","caller":"traceutil/trace.go:171","msg":"trace[409135754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"432.205029ms","start":"2026-04-28T03:16:00.531197Z","end":"2026-04-28T03:16:00.963402Z","steps":["trace[409135754] 'agreement among raft nodes before linearized reading' (duration: 431.983244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:00.963453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:00.531183Z","time spent":"432.262001ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:16:00.96357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.242911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T03:16:00.963658Z","caller":"traceutil/trace.go:171","msg":"trace[860107426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"432.346443ms","start":"2026-04-28T03:16:00.531293Z","end":"2026-04-28T03:16:00.96364Z","steps":["trace[860107426] 'agreement among raft nodes before linearized reading' (duration: 432.114467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:00.963767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:00.531243Z","time spent":"432.499296ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:16:00.964058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.605488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:16:00.964088Z","caller":"traceutil/trace.go:171","msg":"trace[122808983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:720; }","duration":"432.67531ms","start":"2026-04-28T03:16:00.531404Z","end":"2026-04-28T03:16:00.964079Z","steps":["trace[122808983] 'agreement among raft nodes before linearized reading' (duration: 432.590778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:00.964119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:00.531375Z","time spent":"432.738702ms","remote":"127.0.0.1:47066","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-04-28T03:16:00.964284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.864144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:16:00.964309Z","caller":"traceutil/trace.go:171","msg":"trace[569798487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"432.924776ms","start":"2026-04-28T03:16:00.531377Z","end":"2026-04-28T03:16:00.964302Z","steps":["trace[569798487] 'agreement among raft nodes before linearized reading' (duration: 432.851504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:00.964336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:00.531359Z","time spent":"432.969066ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T03:16:04.668977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828279072470877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:16:04.722804Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.1877925s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:16:04.724474Z","caller":"traceutil/trace.go:171","msg":"trace[355284602] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"556.212285ms","start":"2026-04-28T03:16:04.168229Z","end":"2026-04-28T03:16:04.724441Z","steps":["trace[355284602] 'read index received' (duration: 555.04527ms)","trace[355284602] 'applied index is now lower than readState.Index' (duration: 1.163435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:16:04.725263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.006093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:16:04.725296Z","caller":"traceutil/trace.go:171","msg":"trace[124476981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"557.074995ms","start":"2026-04-28T03:16:04.168213Z","end":"2026-04-28T03:16:04.725288Z","steps":["trace[124476981] 'agreement among raft nodes before linearized reading' (duration: 556.321708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:04.725326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:04.168132Z","time spent":"557.188848ms","remote":"127.0.0.1:46840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:16:04.728108Z","caller":"traceutil/trace.go:171","msg":"trace[537220904] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"1.193236443s","start":"2026-04-28T03:16:03.534861Z","end":"2026-04-28T03:16:04.728098Z","steps":["trace[537220904] 'process raft request' (duration: 1.188431006s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:04.728218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:03.534812Z","time spent":"1.193331565s","remote":"127.0.0.1:47042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:16:12.746313Z","caller":"traceutil/trace.go:171","msg":"trace[1463832736] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"109.286778ms","start":"2026-04-28T03:16:12.637002Z","end":"2026-04-28T03:16:12.746289Z","steps":["trace[1463832736] 'process raft request' (duration: 109.081543ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:13.473648Z","caller":"traceutil/trace.go:171","msg":"trace[671773910] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"118.035333ms","start":"2026-04-28T03:16:13.355559Z","end":"2026-04-28T03:16:13.473594Z","steps":["trace[671773910] 'process raft request' (duration: 66.06736ms)","trace[671773910] 'compare' (duration: 51.508913ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:13.614651Z","caller":"traceutil/trace.go:171","msg":"trace[908311439] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"106.631678ms","start":"2026-04-28T03:16:13.507987Z","end":"2026-04-28T03:16:13.614582Z","steps":["trace[908311439] 'process raft request' (duration: 106.460564ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:31.405582Z","caller":"traceutil/trace.go:171","msg":"trace[1082648378] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"159.743888ms","start":"2026-04-28T03:16:31.245826Z","end":"2026-04-28T03:16:31.40557Z","steps":["trace[1082648378] 'read index received' (duration: 159.635956ms)","trace[1082648378] 'applied index is now lower than readState.Index' (duration: 107.412µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:31.405721Z","caller":"traceutil/trace.go:171","msg":"trace[400319087] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"167.167593ms","start":"2026-04-28T03:16:31.238549Z","end":"2026-04-28T03:16:31.405716Z","steps":["trace[400319087] 'process raft request' (duration: 166.948798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:31.405978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.149188ms","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-04-28T03:16:31.405961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.617445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T03:16:31.406002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.406518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T03:16:31.406035Z","caller":"traceutil/trace.go:171","msg":"trace[1385247459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"159.719387ms","start":"2026-04-28T03:16:31.246297Z","end":"2026-04-28T03:16:31.406016Z","steps":["trace[1385247459] 'agreement among raft nodes before linearized reading' (duration: 159.506951ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:31.406056Z","caller":"traceutil/trace.go:171","msg":"trace[212854085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"133.462619ms","start":"2026-04-28T03:16:31.27258Z","end":"2026-04-28T03:16:31.406042Z","steps":["trace[212854085] 'agreement among raft nodes before linearized reading' (duration: 133.321175ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:31.406Z","caller":"traceutil/trace.go:171","msg":"trace[816493928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"160.244969ms","start":"2026-04-28T03:16:31.245749Z","end":"2026-04-28T03:16:31.405994Z","steps":["trace[816493928] 'agreement among raft nodes before linearized reading' (duration: 160.188548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:31.61153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.183199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:16:31.611908Z","caller":"traceutil/trace.go:171","msg":"trace[1225831547] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"201.84917ms","start":"2026-04-28T03:16:31.409826Z","end":"2026-04-28T03:16:31.611675Z","steps":["trace[1225831547] 'process raft request' (duration: 61.448836ms)","trace[1225831547] 'compare' (duration: 140.099077ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:31.612099Z","caller":"traceutil/trace.go:171","msg":"trace[1431429847] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"201.662536ms","start":"2026-04-28T03:16:31.410401Z","end":"2026-04-28T03:16:31.612064Z","steps":["trace[1431429847] 'process raft request' (duration: 201.237357ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:31.612163Z","caller":"traceutil/trace.go:171","msg":"trace[1535299163] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"201.454792ms","start":"2026-04-28T03:16:31.410655Z","end":"2026-04-28T03:16:31.61211Z","steps":["trace[1535299163] 'process raft request' (duration: 201.280218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:51.860889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.308646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:16:51.860957Z","caller":"traceutil/trace.go:171","msg":"trace[443942622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"100.427519ms","start":"2026-04-28T03:16:51.760512Z","end":"2026-04-28T03:16:51.86094Z","steps":["trace[443942622] 'range keys from in-memory index tree' (duration: 100.103742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:51.861031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.606805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T03:16:51.861091Z","caller":"traceutil/trace.go:171","msg":"trace[1080616127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"139.707326ms","start":"2026-04-28T03:16:51.721367Z","end":"2026-04-28T03:16:51.861074Z","steps":["trace[1080616127] 'range keys from in-memory index tree' (duration: 139.44621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:51.861322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.971909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:16:51.861425Z","caller":"traceutil/trace.go:171","msg":"trace[705907919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:992; }","duration":"143.133332ms","start":"2026-04-28T03:16:51.718272Z","end":"2026-04-28T03:16:51.861405Z","steps":["trace[705907919] 'range keys from in-memory index tree' (duration: 142.381295ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:51.995215Z","caller":"traceutil/trace.go:171","msg":"trace[1953063881] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"129.999071ms","start":"2026-04-28T03:16:51.865196Z","end":"2026-04-28T03:16:51.995195Z","steps":["trace[1953063881] 'process raft request' (duration: 119.452218ms)","trace[1953063881] 'compare' (duration: 10.369469ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:51.995444Z","caller":"traceutil/trace.go:171","msg":"trace[1588340334] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"130.188195ms","start":"2026-04-28T03:16:51.865249Z","end":"2026-04-28T03:16:51.995437Z","steps":["trace[1588340334] 'process raft request' (duration: 129.889689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:53.912245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.697644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:16:53.912453Z","caller":"traceutil/trace.go:171","msg":"trace[759431889] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"252.11842ms","start":"2026-04-28T03:16:53.660297Z","end":"2026-04-28T03:16:53.912416Z","steps":["trace[759431889] 'process raft request' (duration: 104.162751ms)","trace[759431889] 'compare' (duration: 147.55857ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:53.912645Z","caller":"traceutil/trace.go:171","msg":"trace[895093973] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"221.046644ms","start":"2026-04-28T03:16:53.691567Z","end":"2026-04-28T03:16:53.912614Z","steps":["trace[895093973] 'process raft request' (duration: 220.771178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:54.121189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.104081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:16:54.121416Z","caller":"traceutil/trace.go:171","msg":"trace[1217565204] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"119.447618ms","start":"2026-04-28T03:16:54.00195Z","end":"2026-04-28T03:16:54.121398Z","steps":["trace[1217565204] 'process raft request' (duration: 14.061811ms)","trace[1217565204] 'compare' (duration: 105.014539ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:54.121785Z","caller":"traceutil/trace.go:171","msg":"trace[17308137] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"117.408714ms","start":"2026-04-28T03:16:54.004359Z","end":"2026-04-28T03:16:54.121768Z","steps":["trace[17308137] 'process raft request' (duration: 117.239001ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:54.464351Z","caller":"traceutil/trace.go:171","msg":"trace[2059963069] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"284.802592ms","start":"2026-04-28T03:16:54.179467Z","end":"2026-04-28T03:16:54.464269Z","steps":["trace[2059963069] 'process raft request' (duration: 281.865337ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.194382Z","caller":"traceutil/trace.go:171","msg":"trace[293159005] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"150.182988ms","start":"2026-04-28T03:17:04.044154Z","end":"2026-04-28T03:17:04.194337Z","steps":["trace[293159005] 'process raft request' (duration: 122.799143ms)","trace[293159005] 'compare' (duration: 27.215671ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:04.265434Z","caller":"traceutil/trace.go:171","msg":"trace[743690522] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"175.343573ms","start":"2026-04-28T03:17:04.090064Z","end":"2026-04-28T03:17:04.265407Z","steps":["trace[743690522] 'process raft request' (duration: 175.058367ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.372561Z","caller":"traceutil/trace.go:171","msg":"trace[804328502] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"102.680599ms","start":"2026-04-28T03:17:04.26981Z","end":"2026-04-28T03:17:04.37249Z","steps":["trace[804328502] 'process raft request' (duration: 88.414883ms)","trace[804328502] 'compare' (duration: 13.890187ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:04.373253Z","caller":"traceutil/trace.go:171","msg":"trace[1128946538] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"102.999216ms","start":"2026-04-28T03:17:04.270185Z","end":"2026-04-28T03:17:04.373184Z","steps":["trace[1128946538] 'process raft request' (duration: 102.225628ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:22.240429Z","caller":"traceutil/trace.go:171","msg":"trace[307705690] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"143.537121ms","start":"2026-04-28T03:17:22.096859Z","end":"2026-04-28T03:17:22.240396Z","steps":["trace[307705690] 'process raft request' (duration: 143.315876ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:24.01394Z","caller":"traceutil/trace.go:171","msg":"trace[2022424] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"149.610146ms","start":"2026-04-28T03:17:23.864306Z","end":"2026-04-28T03:17:24.013916Z","steps":["trace[2022424] 'process raft request' (duration: 149.469563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:36.847378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.252454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:17:36.847463Z","caller":"traceutil/trace.go:171","msg":"trace[726971020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1139; }","duration":"176.396407ms","start":"2026-04-28T03:17:36.671048Z","end":"2026-04-28T03:17:36.847445Z","steps":["trace[726971020] 'range keys from in-memory index tree' (duration: 176.104291ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:37.007487Z","caller":"traceutil/trace.go:171","msg":"trace[519352310] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"155.123967ms","start":"2026-04-28T03:17:36.852333Z","end":"2026-04-28T03:17:37.007457Z","steps":["trace[519352310] 'process raft request' (duration: 154.797839ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:37.022905Z","caller":"traceutil/trace.go:171","msg":"trace[1564773486] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"169.656229ms","start":"2026-04-28T03:17:36.853227Z","end":"2026-04-28T03:17:37.022883Z","steps":["trace[1564773486] 'process raft request' (duration: 169.555447ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:05.207031Z","caller":"traceutil/trace.go:171","msg":"trace[986174149] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"210.537193ms","start":"2026-04-28T03:18:04.996466Z","end":"2026-04-28T03:18:05.207003Z","steps":["trace[986174149] 'process raft request' (duration: 210.335718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:05.62816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.093564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:18:05.628259Z","caller":"traceutil/trace.go:171","msg":"trace[4207274] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"363.487665ms","start":"2026-04-28T03:18:05.264755Z","end":"2026-04-28T03:18:05.628242Z","steps":["trace[4207274] 'read index received' (duration: 132.264029ms)","trace[4207274] 'applied index is now lower than readState.Index' (duration: 231.222296ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:18:05.628339Z","caller":"traceutil/trace.go:171","msg":"trace[915557274] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"417.760789ms","start":"2026-04-28T03:18:05.210531Z","end":"2026-04-28T03:18:05.628292Z","steps":["trace[915557274] 'process raft request' (duration: 186.442831ms)","trace[915557274] 'compare' (duration: 230.730635ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:18:05.628401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.653059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T03:18:05.628431Z","caller":"traceutil/trace.go:171","msg":"trace[396955686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"363.71921ms","start":"2026-04-28T03:18:05.264702Z","end":"2026-04-28T03:18:05.628421Z","steps":["trace[396955686] 'agreement among raft nodes before linearized reading' (duration: 363.585247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:05.628473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:05.264683Z","time spent":"363.779482ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T03:18:05.628511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:05.210511Z","time spent":"417.906042ms","remote":"127.0.0.1:47066","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-04-28T03:18:06.61595Z","caller":"traceutil/trace.go:171","msg":"trace[454204132] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"257.100446ms","start":"2026-04-28T03:18:06.35883Z","end":"2026-04-28T03:18:06.61593Z","steps":["trace[454204132] 'process raft request' (duration: 256.984724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:06.891708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.573903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:18:06.891846Z","caller":"traceutil/trace.go:171","msg":"trace[1235670948] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"270.234776ms","start":"2026-04-28T03:18:06.621572Z","end":"2026-04-28T03:18:06.891807Z","steps":["trace[1235670948] 'process raft request' (duration: 114.50732ms)","trace[1235670948] 'compare' (duration: 155.070982ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:18:59.575748Z","caller":"traceutil/trace.go:171","msg":"trace[1404660364] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"110.372968ms","start":"2026-04-28T03:18:59.465331Z","end":"2026-04-28T03:18:59.575704Z","steps":["trace[1404660364] 'process raft request' (duration: 110.209595ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:00.380594Z","caller":"traceutil/trace.go:171","msg":"trace[378876095] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"177.183654ms","start":"2026-04-28T03:19:00.203385Z","end":"2026-04-28T03:19:00.380568Z","steps":["trace[378876095] 'process raft request' (duration: 177.034161ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:00.838809Z","caller":"traceutil/trace.go:171","msg":"trace[1104888543] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1473; }","duration":"181.781337ms","start":"2026-04-28T03:19:00.657008Z","end":"2026-04-28T03:19:00.838789Z","steps":["trace[1104888543] 'read index received' (duration: 181.600493ms)","trace[1104888543] 'applied index is now lower than readState.Index' (duration: 180.014µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:00.838938Z","caller":"traceutil/trace.go:171","msg":"trace[1125700515] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"206.576841ms","start":"2026-04-28T03:19:00.632337Z","end":"2026-04-28T03:19:00.838914Z","steps":["trace[1125700515] 'process raft request' (duration: 206.329635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:00.838957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.92779ms","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-04-28T03:19:00.839012Z","caller":"traceutil/trace.go:171","msg":"trace[1587568402] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1413; }","duration":"182.042423ms","start":"2026-04-28T03:19:00.656961Z","end":"2026-04-28T03:19:00.839003Z","steps":["trace[1587568402] 'agreement among raft nodes before linearized reading' (duration: 181.92652ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:01.160928Z","caller":"traceutil/trace.go:171","msg":"trace[1595612583] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"129.609589ms","start":"2026-04-28T03:19:01.031292Z","end":"2026-04-28T03:19:01.160901Z","steps":["trace[1595612583] 'process raft request' (duration: 129.445126ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:01.501936Z","caller":"traceutil/trace.go:171","msg":"trace[1920983991] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"145.146147ms","start":"2026-04-28T03:19:01.356757Z","end":"2026-04-28T03:19:01.501903Z","steps":["trace[1920983991] 'process raft request' (duration: 119.465672ms)","trace[1920983991] 'compare' (duration: 25.534421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:03.580695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.235777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T03:19:03.580785Z","caller":"traceutil/trace.go:171","msg":"trace[1268620116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1421; }","duration":"267.36842ms","start":"2026-04-28T03:19:03.313398Z","end":"2026-04-28T03:19:03.580767Z","steps":["trace[1268620116] 'range keys from in-memory index tree' (duration: 267.073024ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:03.789693Z","caller":"traceutil/trace.go:171","msg":"trace[1130087231] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"203.674506ms","start":"2026-04-28T03:19:03.585989Z","end":"2026-04-28T03:19:03.789664Z","steps":["trace[1130087231] 'process raft request' (duration: 104.18547ms)","trace[1130087231] 'compare' (duration: 98.953713ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:03.827318Z","caller":"traceutil/trace.go:171","msg":"trace[1279775484] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"141.820303ms","start":"2026-04-28T03:19:03.685471Z","end":"2026-04-28T03:19:03.827291Z","steps":["trace[1279775484] 'process raft request' (duration: 141.653509ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:31.864195Z","caller":"traceutil/trace.go:171","msg":"trace[1091690230] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"160.075811ms","start":"2026-04-28T03:19:31.70409Z","end":"2026-04-28T03:19:31.864166Z","steps":["trace[1091690230] 'process raft request' (duration: 159.805725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:40.195067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.168825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:19:40.195328Z","caller":"traceutil/trace.go:171","msg":"trace[1553792867] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"146.584158ms","start":"2026-04-28T03:19:40.048719Z","end":"2026-04-28T03:19:40.195303Z","steps":["trace[1553792867] 'process raft request' (duration: 142.801814ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:40.195417Z","caller":"traceutil/trace.go:171","msg":"trace[290707327] linearizableReadLoop","detail":"{readStateIndex:1609; appliedIndex:1608; }","duration":"118.831918ms","start":"2026-04-28T03:19:40.076556Z","end":"2026-04-28T03:19:40.195388Z","steps":["trace[290707327] 'read index received' (duration: 115.159366ms)","trace[290707327] 'applied index is now lower than readState.Index' (duration: 3.669662ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:40.195745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.161315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:19:40.195827Z","caller":"traceutil/trace.go:171","msg":"trace[430433673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1539; }","duration":"258.287947ms","start":"2026-04-28T03:19:39.936836Z","end":"2026-04-28T03:19:40.195123Z","steps":["trace[430433673] 'range keys from in-memory index tree' (duration: 257.990901ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:40.195881Z","caller":"traceutil/trace.go:171","msg":"trace[1083785549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1540; }","duration":"119.337959ms","start":"2026-04-28T03:19:40.076515Z","end":"2026-04-28T03:19:40.195853Z","steps":["trace[1083785549] 'agreement among raft nodes before linearized reading' (duration: 118.981041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:41.099091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828279072472898,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:19:41.599659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828279072472898,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:19:41.96753Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.767810684s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:19:41.967982Z","caller":"traceutil/trace.go:171","msg":"trace[125606355] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"1.768371137s","start":"2026-04-28T03:19:40.199585Z","end":"2026-04-28T03:19:41.967956Z","steps":["trace[125606355] 'process raft request' (duration: 1.768231594s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:41.96829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:40.19957Z","time spent":"1.768573991s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:19:42.308344Z","caller":"traceutil/trace.go:171","msg":"trace[1956163286] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"2.108607309s","start":"2026-04-28T03:19:40.199704Z","end":"2026-04-28T03:19:42.308311Z","steps":["trace[1956163286] 'process raft request' (duration: 2.105347075s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:42.308483Z","caller":"traceutil/trace.go:171","msg":"trace[370562288] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"355.165985ms","start":"2026-04-28T03:19:41.953305Z","end":"2026-04-28T03:19:42.308471Z","steps":["trace[370562288] 'process raft request' (duration: 354.880919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:42.308571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:41.953278Z","time spent":"355.236926ms","remote":"127.0.0.1:47066","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-04-28T03:19:42.30862Z","caller":"traceutil/trace.go:171","msg":"trace[2076187525] linearizableReadLoop","detail":"{readStateIndex:1611; appliedIndex:1609; }","duration":"1.710131215s","start":"2026-04-28T03:19:40.598455Z","end":"2026-04-28T03:19:42.308586Z","steps":["trace[2076187525] 'read index received' (duration: 1.369214028s)","trace[2076187525] 'applied index is now lower than readState.Index' (duration: 340.910807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:42.308912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710442361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-28T03:19:42.308862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:40.199677Z","time spent":"2.108807942s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:19:42.308954Z","caller":"traceutil/trace.go:171","msg":"trace[1593525999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1544; }","duration":"1.710541864s","start":"2026-04-28T03:19:40.598401Z","end":"2026-04-28T03:19:42.308943Z","steps":["trace[1593525999] 'agreement among raft nodes before linearized reading' (duration: 1.710343929s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:42.308993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:40.598374Z","time spent":"1.710610186s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:19:42.30899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401464829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:19:42.30836Z","caller":"traceutil/trace.go:171","msg":"trace[12751664] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"106.585344ms","start":"2026-04-28T03:19:42.201739Z","end":"2026-04-28T03:19:42.308324Z","steps":["trace[12751664] 'process raft request' (duration: 106.534093ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:42.309066Z","caller":"traceutil/trace.go:171","msg":"trace[800353112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1544; }","duration":"1.401589163s","start":"2026-04-28T03:19:40.907459Z","end":"2026-04-28T03:19:42.309049Z","steps":["trace[800353112] 'agreement among raft nodes before linearized reading' (duration: 1.401364478s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:42.30907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.894935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:19:42.309111Z","caller":"traceutil/trace.go:171","msg":"trace[1410792358] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1544; }","duration":"804.982237ms","start":"2026-04-28T03:19:41.504118Z","end":"2026-04-28T03:19:42.309101Z","steps":["trace[1410792358] 'agreement among raft nodes before linearized reading' (duration: 804.902455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:42.309151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:41.504099Z","time spent":"805.045309ms","remote":"127.0.0.1:47236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:19:42.309181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343504983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:19:42.30921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.663503ms","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-04-28T03:19:42.309207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.028327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:19:42.309239Z","caller":"traceutil/trace.go:171","msg":"trace[513551999] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1544; }","duration":"987.732695ms","start":"2026-04-28T03:19:41.321498Z","end":"2026-04-28T03:19:42.309231Z","steps":["trace[513551999] 'agreement among raft nodes before linearized reading' (duration: 987.633343ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:42.309234Z","caller":"traceutil/trace.go:171","msg":"trace[889081748] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1544; }","duration":"1.343558935s","start":"2026-04-28T03:19:40.965658Z","end":"2026-04-28T03:19:42.309216Z","steps":["trace[889081748] 'agreement among raft nodes before linearized reading' (duration: 1.343475103s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:42.309269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:41.321476Z","time spent":"987.785977ms","remote":"127.0.0.1:46894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":92,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-28T03:19:42.309271Z","caller":"traceutil/trace.go:171","msg":"trace[1263243139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1544; }","duration":"142.155611ms","start":"2026-04-28T03:19:42.167101Z","end":"2026-04-28T03:19:42.309257Z","steps":["trace[1263243139] 'agreement among raft nodes before linearized reading' (duration: 142.066899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:42.309287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:40.965609Z","time spent":"1.343662957s","remote":"127.0.0.1:59922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:19:42.309361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.09207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T03:19:42.309389Z","caller":"traceutil/trace.go:171","msg":"trace[1053741580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1544; }","duration":"721.159641ms","start":"2026-04-28T03:19:41.588221Z","end":"2026-04-28T03:19:42.309381Z","steps":["trace[1053741580] 'agreement among raft nodes before linearized reading' (duration: 721.061639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:42.309414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:41.588197Z","time spent":"721.210014ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T03:19:42.309109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:40.907442Z","time spent":"1.401659205s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T03:20:04.22477Z","caller":"traceutil/trace.go:171","msg":"trace[320248394] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"140.832666ms","start":"2026-04-28T03:20:04.083914Z","end":"2026-04-28T03:20:04.224747Z","steps":["trace[320248394] 'process raft request' (duration: 140.678753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:04.983314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.055578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:20:04.98347Z","caller":"traceutil/trace.go:171","msg":"trace[1882438843] linearizableReadLoop","detail":"{readStateIndex:1690; appliedIndex:1689; }","duration":"564.458701ms","start":"2026-04-28T03:20:04.418992Z","end":"2026-04-28T03:20:04.983451Z","steps":["trace[1882438843] 'read index received' (duration: 116.112967ms)","trace[1882438843] 'applied index is now lower than readState.Index' (duration: 448.344064ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:04.983522Z","caller":"traceutil/trace.go:171","msg":"trace[514999355] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"606.012925ms","start":"2026-04-28T03:20:04.377473Z","end":"2026-04-28T03:20:04.983486Z","steps":["trace[514999355] 'process raft request' (duration: 157.687851ms)","trace[514999355] 'compare' (duration: 447.544697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:04.983697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.698716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-28T03:20:04.983702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.377442Z","time spent":"606.163819ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:04.983744Z","caller":"traceutil/trace.go:171","msg":"trace[2016311324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1616; }","duration":"564.791508ms","start":"2026-04-28T03:20:04.41894Z","end":"2026-04-28T03:20:04.983732Z","steps":["trace[2016311324] 'agreement among raft nodes before linearized reading' (duration: 564.611564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:04.983819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.418912Z","time spent":"564.89677ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:20:04.984004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.62568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:04.984056Z","caller":"traceutil/trace.go:171","msg":"trace[2040970449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1616; }","duration":"417.701331ms","start":"2026-04-28T03:20:04.566341Z","end":"2026-04-28T03:20:04.984043Z","steps":["trace[2040970449] 'agreement among raft nodes before linearized reading' (duration: 417.62995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:04.984096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.566326Z","time spent":"417.762953ms","remote":"127.0.0.1:46840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T03:20:04.984122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.556788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:20:04.984187Z","caller":"traceutil/trace.go:171","msg":"trace[580348619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1616; }","duration":"518.66253ms","start":"2026-04-28T03:20:04.465512Z","end":"2026-04-28T03:20:04.984175Z","steps":["trace[580348619] 'agreement among raft nodes before linearized reading' (duration: 518.466216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:04.984217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.465472Z","time spent":"518.738982ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T03:20:04.984256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.678463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-28T03:20:04.984292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.30659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T03:20:04.984331Z","caller":"traceutil/trace.go:171","msg":"trace[410287842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1616; }","duration":"565.377401ms","start":"2026-04-28T03:20:04.418945Z","end":"2026-04-28T03:20:04.984322Z","steps":["trace[410287842] 'agreement among raft nodes before linearized reading' (duration: 565.276129ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:04.984357Z","caller":"traceutil/trace.go:171","msg":"trace[834840285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1616; }","duration":"524.067771ms","start":"2026-04-28T03:20:04.460253Z","end":"2026-04-28T03:20:04.98432Z","steps":["trace[834840285] 'agreement among raft nodes before linearized reading' (duration: 523.481468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:04.984378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.418924Z","time spent":"565.445872ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:20:04.984395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.460229Z","time spent":"524.156303ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T03:20:05.376505Z","caller":"traceutil/trace.go:171","msg":"trace[1757375011] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"388.597145ms","start":"2026-04-28T03:20:04.98788Z","end":"2026-04-28T03:20:05.376477Z","steps":["trace[1757375011] 'process raft request' (duration: 388.455232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:05.376832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.98786Z","time spent":"388.725807ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:05.393526Z","caller":"traceutil/trace.go:171","msg":"trace[476713015] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"405.408908ms","start":"2026-04-28T03:20:04.988086Z","end":"2026-04-28T03:20:05.393495Z","steps":["trace[476713015] 'process raft request' (duration: 405.240084ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:05.3939Z","caller":"traceutil/trace.go:171","msg":"trace[291558491] linearizableReadLoop","detail":"{readStateIndex:1694; appliedIndex:1691; }","duration":"225.746937ms","start":"2026-04-28T03:20:05.168133Z","end":"2026-04-28T03:20:05.39388Z","steps":["trace[291558491] 'read index received' (duration: 208.607316ms)","trace[291558491] 'applied index is now lower than readState.Index' (duration: 17.138331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:05.393799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.988062Z","time spent":"405.567943ms","remote":"127.0.0.1:47066","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-04-28T03:20:05.394004Z","caller":"traceutil/trace.go:171","msg":"trace[1083463941] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"405.215323ms","start":"2026-04-28T03:20:04.988763Z","end":"2026-04-28T03:20:05.393978Z","steps":["trace[1083463941] 'process raft request' (duration: 405.037289ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:05.394005Z","caller":"traceutil/trace.go:171","msg":"trace[1845250553] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"405.867878ms","start":"2026-04-28T03:20:04.988104Z","end":"2026-04-28T03:20:05.393972Z","steps":["trace[1845250553] 'process raft request' (duration: 405.334737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:05.394032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.88875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:20:05.394092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.872596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:05.394127Z","caller":"traceutil/trace.go:171","msg":"trace[1335043624] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1620; }","duration":"166.905906ms","start":"2026-04-28T03:20:05.22721Z","end":"2026-04-28T03:20:05.394116Z","steps":["trace[1335043624] 'agreement among raft nodes before linearized reading' (duration: 166.853235ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:05.394126Z","caller":"traceutil/trace.go:171","msg":"trace[1614647620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1620; }","duration":"226.028293ms","start":"2026-04-28T03:20:05.168087Z","end":"2026-04-28T03:20:05.394115Z","steps":["trace[1614647620] 'agreement among raft nodes before linearized reading' (duration: 225.906651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:05.394135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.988748Z","time spent":"405.314516ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:05.394224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:04.988084Z","time spent":"405.98643ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:06.545184Z","caller":"traceutil/trace.go:171","msg":"trace[53260347] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"312.806876ms","start":"2026-04-28T03:20:06.232356Z","end":"2026-04-28T03:20:06.545163Z","steps":["trace[53260347] 'process raft request' (duration: 312.652732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:06.545331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:06.23233Z","time spent":"312.922368ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:07.419423Z","caller":"traceutil/trace.go:171","msg":"trace[751614287] linearizableReadLoop","detail":"{readStateIndex:1696; appliedIndex:1695; }","duration":"251.703094ms","start":"2026-04-28T03:20:07.167683Z","end":"2026-04-28T03:20:07.419386Z","steps":["trace[751614287] 'read index received' (duration: 251.460109ms)","trace[751614287] 'applied index is now lower than readState.Index' (duration: 241.205µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:07.419479Z","caller":"traceutil/trace.go:171","msg":"trace[439434842] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"429.159776ms","start":"2026-04-28T03:20:06.990279Z","end":"2026-04-28T03:20:07.419439Z","steps":["trace[439434842] 'process raft request' (duration: 428.830769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:07.419629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.921059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:20:07.419765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:06.99025Z","time spent":"429.427404ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:07.419848Z","caller":"traceutil/trace.go:171","msg":"trace[954917020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1622; }","duration":"252.189686ms","start":"2026-04-28T03:20:07.167641Z","end":"2026-04-28T03:20:07.419831Z","steps":["trace[954917020] 'agreement among raft nodes before linearized reading' (duration: 251.909219ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:08.914627Z","caller":"traceutil/trace.go:171","msg":"trace[1683787455] linearizableReadLoop","detail":"{readStateIndex:1703; appliedIndex:1702; }","duration":"321.328046ms","start":"2026-04-28T03:20:08.593272Z","end":"2026-04-28T03:20:08.9146Z","steps":["trace[1683787455] 'read index received' (duration: 321.079981ms)","trace[1683787455] 'applied index is now lower than readState.Index' (duration: 247.025µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:08.914753Z","caller":"traceutil/trace.go:171","msg":"trace[250818881] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"360.694593ms","start":"2026-04-28T03:20:08.554007Z","end":"2026-04-28T03:20:08.914701Z","steps":["trace[250818881] 'process raft request' (duration: 360.426827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:08.914775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.47935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:08.91483Z","caller":"traceutil/trace.go:171","msg":"trace[1255398579] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1628; }","duration":"321.589511ms","start":"2026-04-28T03:20:08.593228Z","end":"2026-04-28T03:20:08.914818Z","steps":["trace[1255398579] 'agreement among raft nodes before linearized reading' (duration: 321.487019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:08.914891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:08.593209Z","time spent":"321.666744ms","remote":"127.0.0.1:46946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:20:08.914906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:08.553979Z","time spent":"360.846796ms","remote":"127.0.0.1:47066","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-04-28T03:20:09.527692Z","caller":"traceutil/trace.go:171","msg":"trace[483599100] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"100.217801ms","start":"2026-04-28T03:20:09.427442Z","end":"2026-04-28T03:20:09.52766Z","steps":["trace[483599100] 'process raft request' (duration: 99.980506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:10.047936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.576191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:20:10.048174Z","caller":"traceutil/trace.go:171","msg":"trace[1730381529] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"507.670165ms","start":"2026-04-28T03:20:09.540479Z","end":"2026-04-28T03:20:10.048149Z","steps":["trace[1730381529] 'process raft request' (duration: 340.796098ms)","trace[1730381529] 'compare' (duration: 166.071268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:10.048281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:09.54046Z","time spent":"507.774408ms","remote":"127.0.0.1:47066","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-04-28T03:20:10.048482Z","caller":"traceutil/trace.go:171","msg":"trace[1358729957] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"507.839589ms","start":"2026-04-28T03:20:09.540617Z","end":"2026-04-28T03:20:10.048456Z","steps":["trace[1358729957] 'process raft request' (duration: 507.43944ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:10.048528Z","caller":"traceutil/trace.go:171","msg":"trace[734082824] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"505.379375ms","start":"2026-04-28T03:20:09.543125Z","end":"2026-04-28T03:20:10.048505Z","steps":["trace[734082824] 'process raft request' (duration: 505.311533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:10.048624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:09.54061Z","time spent":"507.941272ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:10.048662Z","caller":"traceutil/trace.go:171","msg":"trace[596673879] linearizableReadLoop","detail":"{readStateIndex:1708; appliedIndex:1704; }","duration":"473.472764ms","start":"2026-04-28T03:20:09.575172Z","end":"2026-04-28T03:20:10.048645Z","steps":["trace[596673879] 'read index received' (duration: 306.031744ms)","trace[596673879] 'applied index is now lower than readState.Index' (duration: 167.43981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:10.04868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:09.543115Z","time spent":"505.489827ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:10.048796Z","caller":"traceutil/trace.go:171","msg":"trace[1586456997] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"327.711999ms","start":"2026-04-28T03:20:09.721065Z","end":"2026-04-28T03:20:10.048777Z","steps":["trace[1586456997] 'process raft request' (duration: 327.409792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:10.048902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:09.721036Z","time spent":"327.822771ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:10.048913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.73357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:20:10.048971Z","caller":"traceutil/trace.go:171","msg":"trace[316757202] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1634; }","duration":"473.823082ms","start":"2026-04-28T03:20:09.575128Z","end":"2026-04-28T03:20:10.048951Z","steps":["trace[316757202] 'agreement among raft nodes before linearized reading' (duration: 473.586716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:10.04903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:09.575108Z","time spent":"473.907144ms","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-28T03:20:10.049112Z","caller":"traceutil/trace.go:171","msg":"trace[537075136] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"506.390887ms","start":"2026-04-28T03:20:09.542136Z","end":"2026-04-28T03:20:10.048527Z","steps":["trace[537075136] 'process raft request' (duration: 506.186863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:10.049244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:09.542121Z","time spent":"507.062473ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:21.689602Z","caller":"traceutil/trace.go:171","msg":"trace[1114067981] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"123.492491ms","start":"2026-04-28T03:20:21.566087Z","end":"2026-04-28T03:20:21.689579Z","steps":["trace[1114067981] 'process raft request' (duration: 123.347108ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:23.439396Z","caller":"traceutil/trace.go:171","msg":"trace[1449730256] linearizableReadLoop","detail":"{readStateIndex:1804; appliedIndex:1803; }","duration":"469.487636ms","start":"2026-04-28T03:20:22.969888Z","end":"2026-04-28T03:20:23.439376Z","steps":["trace[1449730256] 'read index received' (duration: 469.397774ms)","trace[1449730256] 'applied index is now lower than readState.Index' (duration: 88.782µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:23.439439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:22.644828Z","time spent":"794.603316ms","remote":"127.0.0.1:46850","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T03:20:23.439561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.655629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T03:20:23.439586Z","caller":"traceutil/trace.go:171","msg":"trace[847174948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1724; }","duration":"469.745881ms","start":"2026-04-28T03:20:22.969832Z","end":"2026-04-28T03:20:23.439578Z","steps":["trace[847174948] 'agreement among raft nodes before linearized reading' (duration: 469.624679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:23.439625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:22.969808Z","time spent":"469.810973ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T03:20:23.518168Z","caller":"traceutil/trace.go:171","msg":"trace[1768063769] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"376.107155ms","start":"2026-04-28T03:20:23.142027Z","end":"2026-04-28T03:20:23.518134Z","steps":["trace[1768063769] 'process raft request' (duration: 375.909171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:23.518338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:23.142Z","time spent":"376.253779ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:24.031175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828279072473331,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:20:24.463572Z","caller":"traceutil/trace.go:171","msg":"trace[951165287] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"1.020301973s","start":"2026-04-28T03:20:23.443242Z","end":"2026-04-28T03:20:24.463544Z","steps":["trace[951165287] 'process raft request' (duration: 1.020231921s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:24.463665Z","caller":"traceutil/trace.go:171","msg":"trace[1248295261] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"1.023012403s","start":"2026-04-28T03:20:23.440593Z","end":"2026-04-28T03:20:24.463605Z","steps":["trace[1248295261] 'process raft request' (duration: 1.022715827s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:24.463742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:23.443229Z","time spent":"1.020427865s","remote":"127.0.0.1:47066","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-04-28T03:20:24.463865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:23.440563Z","time spent":"1.023180246s","remote":"127.0.0.1:46850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:24.464218Z","caller":"traceutil/trace.go:171","msg":"trace[1635535321] linearizableReadLoop","detail":"{readStateIndex:1808; appliedIndex:1805; }","duration":"933.713533ms","start":"2026-04-28T03:20:23.530484Z","end":"2026-04-28T03:20:24.464198Z","steps":["trace[1635535321] 'read index received' (duration: 932.780773ms)","trace[1635535321] 'applied index is now lower than readState.Index' (duration: 931.62µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:24.464245Z","caller":"traceutil/trace.go:171","msg":"trace[211433252] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"768.906364ms","start":"2026-04-28T03:20:23.695319Z","end":"2026-04-28T03:20:24.464226Z","steps":["trace[211433252] 'process raft request' (duration: 768.796251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:24.46434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:23.695291Z","time spent":"769.000866ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:24.46435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.851096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:24.464391Z","caller":"traceutil/trace.go:171","msg":"trace[1199869924] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1728; }","duration":"933.940008ms","start":"2026-04-28T03:20:23.530439Z","end":"2026-04-28T03:20:24.464379Z","steps":["trace[1199869924] 'agreement among raft nodes before linearized reading' (duration: 933.864417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:24.464421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:23.530419Z","time spent":"933.99347ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:20:24.464435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.483248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T03:20:24.464484Z","caller":"traceutil/trace.go:171","msg":"trace[2022439466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1728; }","duration":"356.56913ms","start":"2026-04-28T03:20:24.1079Z","end":"2026-04-28T03:20:24.464469Z","steps":["trace[2022439466] 'agreement among raft nodes before linearized reading' (duration: 356.414137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:24.464493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.744696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:20:24.464513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.677854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T03:20:24.464527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:24.107881Z","time spent":"356.634402ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T03:20:24.464543Z","caller":"traceutil/trace.go:171","msg":"trace[1672173349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1728; }","duration":"299.824477ms","start":"2026-04-28T03:20:24.164702Z","end":"2026-04-28T03:20:24.464527Z","steps":["trace[1672173349] 'agreement among raft nodes before linearized reading' (duration: 299.763076ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:24.464558Z","caller":"traceutil/trace.go:171","msg":"trace[576177424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1728; }","duration":"343.749036ms","start":"2026-04-28T03:20:24.120795Z","end":"2026-04-28T03:20:24.464544Z","steps":["trace[576177424] 'agreement among raft nodes before linearized reading' (duration: 343.579232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:24.464546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.533161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T03:20:24.464591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:24.120781Z","time spent":"343.803797ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T03:20:24.46464Z","caller":"traceutil/trace.go:171","msg":"trace[916508195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1728; }","duration":"343.673304ms","start":"2026-04-28T03:20:24.120945Z","end":"2026-04-28T03:20:24.464619Z","steps":["trace[916508195] 'agreement among raft nodes before linearized reading' (duration: 343.411689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:24.464434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.396379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T03:20:24.464686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:24.120935Z","time spent":"343.741356ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T03:20:24.464697Z","caller":"traceutil/trace.go:171","msg":"trace[1763789448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1728; }","duration":"335.683706ms","start":"2026-04-28T03:20:24.129002Z","end":"2026-04-28T03:20:24.464686Z","steps":["trace[1763789448] 'agreement among raft nodes before linearized reading' (duration: 335.319518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:24.464734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:24.128991Z","time spent":"335.732187ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T03:20:36.710189Z","caller":"traceutil/trace.go:171","msg":"trace[2079063859] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"114.328027ms","start":"2026-04-28T03:20:36.595837Z","end":"2026-04-28T03:20:36.710165Z","steps":["trace[2079063859] 'process raft request' (duration: 114.249855ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:36.710209Z","caller":"traceutil/trace.go:171","msg":"trace[769925395] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"118.071909ms","start":"2026-04-28T03:20:36.592111Z","end":"2026-04-28T03:20:36.710183Z","steps":["trace[769925395] 'process raft request' (duration: 80.701637ms)","trace[769925395] 'compare' (duration: 37.137497ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:36.710471Z","caller":"traceutil/trace.go:171","msg":"trace[695671280] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"114.503859ms","start":"2026-04-28T03:20:36.595955Z","end":"2026-04-28T03:20:36.710459Z","steps":["trace[695671280] 'process raft request' (duration: 114.173192ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:36.710514Z","caller":"traceutil/trace.go:171","msg":"trace[1116763905] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"114.416047ms","start":"2026-04-28T03:20:36.596075Z","end":"2026-04-28T03:20:36.710491Z","steps":["trace[1116763905] 'process raft request' (duration: 114.375126ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:36.710623Z","caller":"traceutil/trace.go:171","msg":"trace[869300706] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"114.488059ms","start":"2026-04-28T03:20:36.596057Z","end":"2026-04-28T03:20:36.710545Z","steps":["trace[869300706] 'process raft request' (duration: 114.320355ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:38.410253Z","caller":"traceutil/trace.go:171","msg":"trace[1990404415] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1854; }","duration":"409.548661ms","start":"2026-04-28T03:20:38.00068Z","end":"2026-04-28T03:20:38.410228Z","steps":["trace[1990404415] 'read index received' (duration: 409.288965ms)","trace[1990404415] 'applied index is now lower than readState.Index' (duration: 258.426µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:38.410296Z","caller":"traceutil/trace.go:171","msg":"trace[1456934497] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"510.185412ms","start":"2026-04-28T03:20:37.90008Z","end":"2026-04-28T03:20:38.410265Z","steps":["trace[1456934497] 'process raft request' (duration: 509.988797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:38.410601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:37.900056Z","time spent":"510.404717ms","remote":"127.0.0.1:46894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:20:38.410804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.062512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5332"} {"level":"info","ts":"2026-04-28T03:20:38.410878Z","caller":"traceutil/trace.go:171","msg":"trace[1023456863] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1773; }","duration":"410.242246ms","start":"2026-04-28T03:20:38.000622Z","end":"2026-04-28T03:20:38.410864Z","steps":["trace[1023456863] 'agreement among raft nodes before linearized reading' (duration: 409.737155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:38.410928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:38.000606Z","time spent":"410.307577ms","remote":"127.0.0.1:46978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5355,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-28T03:20:38.862263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.173268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:38.862371Z","caller":"traceutil/trace.go:171","msg":"trace[401413872] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1773; }","duration":"368.327913ms","start":"2026-04-28T03:20:38.494024Z","end":"2026-04-28T03:20:38.862352Z","steps":["trace[401413872] 'range keys from in-memory index tree' (duration: 368.102637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:38.862415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:38.494005Z","time spent":"368.399705ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-04-28T03:20:38.86275Z","caller":"traceutil/trace.go:171","msg":"trace[356373355] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"267.65189ms","start":"2026-04-28T03:20:38.595085Z","end":"2026-04-28T03:20:38.862737Z","steps":["trace[356373355] 'process raft request' (duration: 266.601916ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:38.862789Z","caller":"traceutil/trace.go:171","msg":"trace[561832433] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"148.383375ms","start":"2026-04-28T03:20:38.714379Z","end":"2026-04-28T03:20:38.862762Z","steps":["trace[561832433] 'read index received' (duration: 147.28834ms)","trace[561832433] 'applied index is now lower than readState.Index' (duration: 1.093375ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:38.86466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.99361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-28T03:20:38.864708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.99824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T03:20:38.864743Z","caller":"traceutil/trace.go:171","msg":"trace[1653345134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1775; }","duration":"150.094572ms","start":"2026-04-28T03:20:38.71463Z","end":"2026-04-28T03:20:38.864724Z","steps":["trace[1653345134] 'agreement among raft nodes before linearized reading' (duration: 149.900997ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:38.864773Z","caller":"traceutil/trace.go:171","msg":"trace[366570868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1775; }","duration":"150.076081ms","start":"2026-04-28T03:20:38.714684Z","end":"2026-04-28T03:20:38.86476Z","steps":["trace[366570868] 'agreement among raft nodes before linearized reading' (duration: 149.886467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:38.864827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.414339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:20:38.864913Z","caller":"traceutil/trace.go:171","msg":"trace[437551092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1775; }","duration":"150.541451ms","start":"2026-04-28T03:20:38.71435Z","end":"2026-04-28T03:20:38.864891Z","steps":["trace[437551092] 'agreement among raft nodes before linearized reading' (duration: 148.552018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:38.865176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.415338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T03:20:38.865238Z","caller":"traceutil/trace.go:171","msg":"trace[1311858799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1775; }","duration":"149.48541ms","start":"2026-04-28T03:20:38.71574Z","end":"2026-04-28T03:20:38.865225Z","steps":["trace[1311858799] 'agreement among raft nodes before linearized reading' (duration: 149.341336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:38.865263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.583274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T03:20:38.865309Z","caller":"traceutil/trace.go:171","msg":"trace[1562476749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1775; }","duration":"150.643835ms","start":"2026-04-28T03:20:38.714652Z","end":"2026-04-28T03:20:38.865296Z","steps":["trace[1562476749] 'agreement among raft nodes before linearized reading' (duration: 150.501992ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:44.559501Z","caller":"traceutil/trace.go:171","msg":"trace[889169593] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"491.946655ms","start":"2026-04-28T03:20:44.067531Z","end":"2026-04-28T03:20:44.559477Z","steps":["trace[889169593] 'read index received' (duration: 491.72999ms)","trace[889169593] 'applied index is now lower than readState.Index' (duration: 215.485µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:44.559544Z","caller":"traceutil/trace.go:171","msg":"trace[1826301871] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"507.385059ms","start":"2026-04-28T03:20:44.052106Z","end":"2026-04-28T03:20:44.559491Z","steps":["trace[1826301871] 'process raft request' (duration: 507.181195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:44.559733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:44.052088Z","time spent":"507.535332ms","remote":"127.0.0.1:46894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:20:44.560139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.587329ms","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-04-28T03:20:44.560195Z","caller":"traceutil/trace.go:171","msg":"trace[1851957218] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1844; }","duration":"492.690681ms","start":"2026-04-28T03:20:44.067489Z","end":"2026-04-28T03:20:44.560179Z","steps":["trace[1851957218] 'agreement among raft nodes before linearized reading' (duration: 492.098218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:44.560239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:44.067473Z","time spent":"492.757893ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T03:20:46.163604Z","caller":"traceutil/trace.go:171","msg":"trace[1976123151] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"169.429334ms","start":"2026-04-28T03:20:45.994131Z","end":"2026-04-28T03:20:46.16356Z","steps":["trace[1976123151] 'process raft request' (duration: 169.27957ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:46.66537Z","caller":"traceutil/trace.go:171","msg":"trace[1919387744] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"170.587689ms","start":"2026-04-28T03:20:46.494754Z","end":"2026-04-28T03:20:46.665342Z","steps":["trace[1919387744] 'process raft request' (duration: 170.439746ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:46.817547Z","caller":"traceutil/trace.go:171","msg":"trace[100178417] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"118.645322ms","start":"2026-04-28T03:20:46.698872Z","end":"2026-04-28T03:20:46.817518Z","steps":["trace[100178417] 'read index received' (duration: 51.555287ms)","trace[100178417] 'applied index is now lower than readState.Index' (duration: 67.088485ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:46.817632Z","caller":"traceutil/trace.go:171","msg":"trace[1567226869] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"126.560468ms","start":"2026-04-28T03:20:46.691045Z","end":"2026-04-28T03:20:46.817605Z","steps":["trace[1567226869] 'process raft request' (duration: 59.480245ms)","trace[1567226869] 'compare' (duration: 66.689795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:46.817754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.857266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-04-28T03:20:46.817805Z","caller":"traceutil/trace.go:171","msg":"trace[2062239777] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1857; }","duration":"118.958798ms","start":"2026-04-28T03:20:46.698829Z","end":"2026-04-28T03:20:46.817787Z","steps":["trace[2062239777] 'agreement among raft nodes before linearized reading' (duration: 118.845706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:47.077937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.23581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:20:47.078024Z","caller":"traceutil/trace.go:171","msg":"trace[1474469860] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"151.082505ms","start":"2026-04-28T03:20:46.926929Z","end":"2026-04-28T03:20:47.078012Z","steps":["trace[1474469860] 'read index received' (duration: 31.751µs)","trace[1474469860] 'applied index is now lower than readState.Index' (duration: 151.049524ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:47.078097Z","caller":"traceutil/trace.go:171","msg":"trace[1191988496] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"183.18483ms","start":"2026-04-28T03:20:46.894902Z","end":"2026-04-28T03:20:47.078087Z","steps":["trace[1191988496] 'process raft request' (duration: 30.725565ms)","trace[1191988496] 'compare' (duration: 152.117207ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:47.078365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.518389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T03:20:47.078449Z","caller":"traceutil/trace.go:171","msg":"trace[52424655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1859; }","duration":"127.622872ms","start":"2026-04-28T03:20:46.950803Z","end":"2026-04-28T03:20:47.078426Z","steps":["trace[52424655] 'agreement among raft nodes before linearized reading' (duration: 127.431558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:47.078391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.56958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T03:20:47.078572Z","caller":"traceutil/trace.go:171","msg":"trace[1312777937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1859; }","duration":"144.787154ms","start":"2026-04-28T03:20:46.933764Z","end":"2026-04-28T03:20:47.078551Z","steps":["trace[1312777937] 'agreement among raft nodes before linearized reading' (duration: 144.457187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:47.078666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.31066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T03:20:47.078695Z","caller":"traceutil/trace.go:171","msg":"trace[212979499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1859; }","duration":"134.360032ms","start":"2026-04-28T03:20:46.944328Z","end":"2026-04-28T03:20:47.078688Z","steps":["trace[212979499] 'agreement among raft nodes before linearized reading' (duration: 134.30294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:47.07861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.242396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:20:47.078761Z","caller":"traceutil/trace.go:171","msg":"trace[294873237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"128.43793ms","start":"2026-04-28T03:20:46.950319Z","end":"2026-04-28T03:20:47.078757Z","steps":["trace[294873237] 'agreement among raft nodes before linearized reading' (duration: 128.215764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:47.079221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.283852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T03:20:47.079277Z","caller":"traceutil/trace.go:171","msg":"trace[1043723657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1859; }","duration":"152.377034ms","start":"2026-04-28T03:20:46.926886Z","end":"2026-04-28T03:20:47.079263Z","steps":["trace[1043723657] 'agreement among raft nodes before linearized reading' (duration: 151.29682ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:47.264638Z","caller":"traceutil/trace.go:171","msg":"trace[744458967] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"181.592245ms","start":"2026-04-28T03:20:47.083021Z","end":"2026-04-28T03:20:47.264613Z","steps":["trace[744458967] 'process raft request' (duration: 181.509513ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:47.264921Z","caller":"traceutil/trace.go:171","msg":"trace[2135738101] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"181.537653ms","start":"2026-04-28T03:20:47.083361Z","end":"2026-04-28T03:20:47.264899Z","steps":["trace[2135738101] 'process raft request' (duration: 181.215156ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:47.26502Z","caller":"traceutil/trace.go:171","msg":"trace[52640842] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"182.184477ms","start":"2026-04-28T03:20:47.082814Z","end":"2026-04-28T03:20:47.264999Z","steps":["trace[52640842] 'process raft request' (duration: 122.905747ms)","trace[52640842] 'compare' (duration: 58.673787ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:47.265193Z","caller":"traceutil/trace.go:171","msg":"trace[1211832294] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1944; }","duration":"138.999436ms","start":"2026-04-28T03:20:47.126182Z","end":"2026-04-28T03:20:47.265182Z","steps":["trace[1211832294] 'read index received' (duration: 22.946412ms)","trace[1211832294] 'applied index is now lower than readState.Index' (duration: 116.052134ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:47.265272Z","caller":"traceutil/trace.go:171","msg":"trace[365113201] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"181.633145ms","start":"2026-04-28T03:20:47.08363Z","end":"2026-04-28T03:20:47.265264Z","steps":["trace[365113201] 'process raft request' (duration: 181.200575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:47.265604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.402405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-04-28T03:20:47.265666Z","caller":"traceutil/trace.go:171","msg":"trace[1223869359] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1864; }","duration":"139.508007ms","start":"2026-04-28T03:20:47.126145Z","end":"2026-04-28T03:20:47.265653Z","steps":["trace[1223869359] 'agreement among raft nodes before linearized reading' (duration: 139.366744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:48.099145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.581342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-28T03:20:48.099238Z","caller":"traceutil/trace.go:171","msg":"trace[493429606] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1864; }","duration":"524.705896ms","start":"2026-04-28T03:20:47.57451Z","end":"2026-04-28T03:20:48.099216Z","steps":["trace[493429606] 'range keys from in-memory index tree' (duration: 524.443279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:48.099283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:47.574501Z","time spent":"524.773057ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T03:20:48.099871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.860302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:48.100358Z","caller":"traceutil/trace.go:171","msg":"trace[1389139392] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1864; }","duration":"579.405844ms","start":"2026-04-28T03:20:47.520932Z","end":"2026-04-28T03:20:48.100338Z","steps":["trace[1389139392] 'count revisions from in-memory index tree' (duration: 578.528174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:48.100491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:47.520912Z","time spent":"579.552796ms","remote":"127.0.0.1:59906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T03:20:48.233258Z","caller":"traceutil/trace.go:171","msg":"trace[675020045] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"129.576297ms","start":"2026-04-28T03:20:48.103652Z","end":"2026-04-28T03:20:48.233228Z","steps":["trace[675020045] 'process raft request' (duration: 129.419193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:48.515909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.96773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:20:48.515985Z","caller":"traceutil/trace.go:171","msg":"trace[148214324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"212.055332ms","start":"2026-04-28T03:20:48.303912Z","end":"2026-04-28T03:20:48.515968Z","steps":["trace[148214324] 'range keys from in-memory index tree' (duration: 211.880889ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:55.568985Z","caller":"traceutil/trace.go:171","msg":"trace[2060274180] linearizableReadLoop","detail":"{readStateIndex:2007; appliedIndex:2006; }","duration":"239.276309ms","start":"2026-04-28T03:20:55.329685Z","end":"2026-04-28T03:20:55.568962Z","steps":["trace[2060274180] 'read index received' (duration: 239.158796ms)","trace[2060274180] 'applied index is now lower than readState.Index' (duration: 116.683µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:55.569039Z","caller":"traceutil/trace.go:171","msg":"trace[1665091009] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"242.090901ms","start":"2026-04-28T03:20:55.326927Z","end":"2026-04-28T03:20:55.569017Z","steps":["trace[1665091009] 'process raft request' (duration: 241.900997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:55.569212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.457153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T03:20:55.569271Z","caller":"traceutil/trace.go:171","msg":"trace[2049432084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1921; }","duration":"239.547504ms","start":"2026-04-28T03:20:55.329709Z","end":"2026-04-28T03:20:55.569256Z","steps":["trace[2049432084] 'agreement among raft nodes before linearized reading' (duration: 239.399121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:55.569274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.467421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:20:55.569359Z","caller":"traceutil/trace.go:171","msg":"trace[642823723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1921; }","duration":"238.576123ms","start":"2026-04-28T03:20:55.330766Z","end":"2026-04-28T03:20:55.569342Z","steps":["trace[642823723] 'agreement among raft nodes before linearized reading' (duration: 238.40332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:55.569208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.498674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T03:20:55.569486Z","caller":"traceutil/trace.go:171","msg":"trace[1305420048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1921; }","duration":"239.80981ms","start":"2026-04-28T03:20:55.329664Z","end":"2026-04-28T03:20:55.569473Z","steps":["trace[1305420048] 'agreement among raft nodes before linearized reading' (duration: 239.406151ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:55.733527Z","caller":"traceutil/trace.go:171","msg":"trace[198605317] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"158.934439ms","start":"2026-04-28T03:20:55.574566Z","end":"2026-04-28T03:20:55.733501Z","steps":["trace[198605317] 'process raft request' (duration: 156.474084ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:55.736495Z","caller":"traceutil/trace.go:171","msg":"trace[670413514] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"161.888306ms","start":"2026-04-28T03:20:55.57459Z","end":"2026-04-28T03:20:55.736479Z","steps":["trace[670413514] 'process raft request' (duration: 161.775683ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:55.736656Z","caller":"traceutil/trace.go:171","msg":"trace[1976290239] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"161.941336ms","start":"2026-04-28T03:20:55.574658Z","end":"2026-04-28T03:20:55.7366Z","steps":["trace[1976290239] 'process raft request' (duration: 161.785193ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:59.001917Z","caller":"traceutil/trace.go:171","msg":"trace[878903388] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"100.592384ms","start":"2026-04-28T03:20:58.901295Z","end":"2026-04-28T03:20:59.001888Z","steps":["trace[878903388] 'process raft request' (duration: 76.001805ms)","trace[878903388] 'compare' (duration: 24.395335ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:03.380693Z","caller":"traceutil/trace.go:171","msg":"trace[70967929] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2048; }","duration":"213.239046ms","start":"2026-04-28T03:21:03.167428Z","end":"2026-04-28T03:21:03.380667Z","steps":["trace[70967929] 'read index received' (duration: 212.921889ms)","trace[70967929] 'applied index is now lower than readState.Index' (duration: 316.257µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:03.380856Z","caller":"traceutil/trace.go:171","msg":"trace[708243139] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"255.754165ms","start":"2026-04-28T03:21:03.125082Z","end":"2026-04-28T03:21:03.380837Z","steps":["trace[708243139] 'process raft request' (duration: 255.336506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:03.380999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.545254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:03.38108Z","caller":"traceutil/trace.go:171","msg":"trace[95756652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1961; }","duration":"213.688247ms","start":"2026-04-28T03:21:03.167372Z","end":"2026-04-28T03:21:03.38106Z","steps":["trace[95756652] 'agreement among raft nodes before linearized reading' (duration: 213.523393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:03.38165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.987253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-28T03:21:03.38166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.793445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:21:03.381712Z","caller":"traceutil/trace.go:171","msg":"trace[865670243] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1961; }","duration":"127.104166ms","start":"2026-04-28T03:21:03.254594Z","end":"2026-04-28T03:21:03.381698Z","steps":["trace[865670243] 'agreement among raft nodes before linearized reading' (duration: 126.911702ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:03.381748Z","caller":"traceutil/trace.go:171","msg":"trace[1482042396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1961; }","duration":"133.961379ms","start":"2026-04-28T03:21:03.247767Z","end":"2026-04-28T03:21:03.381729Z","steps":["trace[1482042396] 'agreement among raft nodes before linearized reading' (duration: 133.687512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:04.181095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828279072473842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:21:04.688432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828279072473842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:21:04.992089Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.488913352s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:21:04.992481Z","caller":"traceutil/trace.go:171","msg":"trace[1086376721] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"1.606031716s","start":"2026-04-28T03:21:03.386423Z","end":"2026-04-28T03:21:04.992455Z","steps":["trace[1086376721] 'process raft request' (duration: 1.605853422s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:04.992832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:03.386404Z","time spent":"1.606159169s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:21:05.002368Z","caller":"traceutil/trace.go:171","msg":"trace[975475303] linearizableReadLoop","detail":"{readStateIndex:2052; appliedIndex:2049; }","duration":"1.322213013s","start":"2026-04-28T03:21:03.680131Z","end":"2026-04-28T03:21:05.002344Z","steps":["trace[975475303] 'read index received' (duration: 1.31223258s)","trace[975475303] 'applied index is now lower than readState.Index' (duration: 9.979243ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:05.002437Z","caller":"traceutil/trace.go:171","msg":"trace[769055200] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"701.31573ms","start":"2026-04-28T03:21:04.301094Z","end":"2026-04-28T03:21:05.00241Z","steps":["trace[769055200] 'process raft request' (duration: 701.202217ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:05.002424Z","caller":"traceutil/trace.go:171","msg":"trace[470455255] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"1.61470403s","start":"2026-04-28T03:21:03.387682Z","end":"2026-04-28T03:21:05.002386Z","steps":["trace[470455255] 'process raft request' (duration: 1.614483435s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.00254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:04.301072Z","time spent":"701.403122ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:05.002615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:03.387663Z","time spent":"1.614851443s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:05.002681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236345958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T03:21:05.002753Z","caller":"traceutil/trace.go:171","msg":"trace[362158345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1964; }","duration":"1.236463551s","start":"2026-04-28T03:21:03.766275Z","end":"2026-04-28T03:21:05.002739Z","steps":["trace[362158345] 'agreement among raft nodes before linearized reading' (duration: 1.236315198s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.002796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:03.766251Z","time spent":"1.236535992s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T03:21:05.002829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.303316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-28T03:21:05.002864Z","caller":"traceutil/trace.go:171","msg":"trace[843005586] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1964; }","duration":"358.370906ms","start":"2026-04-28T03:21:04.644483Z","end":"2026-04-28T03:21:05.002854Z","steps":["trace[843005586] 'agreement among raft nodes before linearized reading' (duration: 358.260544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.002805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.923087ms","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-04-28T03:21:05.002681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322541661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T03:21:05.003053Z","caller":"traceutil/trace.go:171","msg":"trace[435894719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1964; }","duration":"1.32296804s","start":"2026-04-28T03:21:03.68008Z","end":"2026-04-28T03:21:05.003048Z","steps":["trace[435894719] 'agreement among raft nodes before linearized reading' (duration: 1.322411447s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.00305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235324454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-28T03:21:05.003073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:03.680059Z","time spent":"1.323008701s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T03:21:05.003084Z","caller":"traceutil/trace.go:171","msg":"trace[1583336111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1964; }","duration":"1.235381056s","start":"2026-04-28T03:21:03.767693Z","end":"2026-04-28T03:21:05.003074Z","steps":["trace[1583336111] 'agreement among raft nodes before linearized reading' (duration: 1.235278404s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.002914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.140378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:21:05.003115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:03.767687Z","time spent":"1.235419347s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T03:21:05.003144Z","caller":"traceutil/trace.go:171","msg":"trace[171037925] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1964; }","duration":"313.399023ms","start":"2026-04-28T03:21:04.689732Z","end":"2026-04-28T03:21:05.003131Z","steps":["trace[171037925] 'agreement among raft nodes before linearized reading' (duration: 313.168718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.003193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:04.689712Z","time spent":"313.470014ms","remote":"127.0.0.1:46948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-28T03:21:05.002972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234750642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:21:05.003246Z","caller":"traceutil/trace.go:171","msg":"trace[1935640881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1964; }","duration":"1.235033388s","start":"2026-04-28T03:21:03.768206Z","end":"2026-04-28T03:21:05.003239Z","steps":["trace[1935640881] 'agreement among raft nodes before linearized reading' (duration: 1.234727111s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.00327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:03.7682Z","time spent":"1.235064899s","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:21:05.002999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.929564ms","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-04-28T03:21:05.003319Z","caller":"traceutil/trace.go:171","msg":"trace[1882373919] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1964; }","duration":"776.250871ms","start":"2026-04-28T03:21:04.227064Z","end":"2026-04-28T03:21:05.003315Z","steps":["trace[1882373919] 'agreement among raft nodes before linearized reading' (duration: 775.919334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.003339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:04.227017Z","time spent":"776.317063ms","remote":"127.0.0.1:46902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-04-28T03:21:05.0034Z","caller":"traceutil/trace.go:171","msg":"trace[375480582] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1964; }","duration":"289.217693ms","start":"2026-04-28T03:21:04.713824Z","end":"2026-04-28T03:21:05.003041Z","steps":["trace[375480582] 'agreement among raft nodes before linearized reading' (duration: 288.923887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.002888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:04.644463Z","time spent":"358.419068ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T03:21:05.003019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.837779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:05.003605Z","caller":"traceutil/trace.go:171","msg":"trace[1841258271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1964; }","duration":"835.456513ms","start":"2026-04-28T03:21:04.168137Z","end":"2026-04-28T03:21:05.003593Z","steps":["trace[1841258271] 'agreement among raft nodes before linearized reading' (duration: 834.8711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.003654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:04.168116Z","time spent":"835.527544ms","remote":"127.0.0.1:46840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:21:05.788778Z","caller":"traceutil/trace.go:171","msg":"trace[490079574] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"400.918936ms","start":"2026-04-28T03:21:05.387845Z","end":"2026-04-28T03:21:05.788764Z","steps":["trace[490079574] 'process raft request' (duration: 400.601099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:05.78894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:05.387805Z","time spent":"401.08172ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:21:39.299376Z","caller":"traceutil/trace.go:171","msg":"trace[1553921973] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"110.399794ms","start":"2026-04-28T03:21:39.188958Z","end":"2026-04-28T03:21:39.299358Z","steps":["trace[1553921973] 'read index received' (duration: 54.521707ms)","trace[1553921973] 'applied index is now lower than readState.Index' (duration: 55.877237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:39.299614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.625569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-28T03:21:39.299745Z","caller":"traceutil/trace.go:171","msg":"trace[1389790441] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:2128; }","duration":"110.782102ms","start":"2026-04-28T03:21:39.188932Z","end":"2026-04-28T03:21:39.299714Z","steps":["trace[1389790441] 'agreement among raft nodes before linearized reading' (duration: 110.546717ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:39.299714Z","caller":"traceutil/trace.go:171","msg":"trace[1594905856] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"112.172723ms","start":"2026-04-28T03:21:39.187495Z","end":"2026-04-28T03:21:39.299668Z","steps":["trace[1594905856] 'process raft request' (duration: 56.03767ms)","trace[1594905856] 'compare' (duration: 55.728224ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:39.4536Z","caller":"traceutil/trace.go:171","msg":"trace[514007073] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"149.697971ms","start":"2026-04-28T03:21:39.303866Z","end":"2026-04-28T03:21:39.453564Z","steps":["trace[514007073] 'process raft request' (duration: 145.612919ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:39.454947Z","caller":"traceutil/trace.go:171","msg":"trace[899444036] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"150.453418ms","start":"2026-04-28T03:21:39.304465Z","end":"2026-04-28T03:21:39.454918Z","steps":["trace[899444036] 'process raft request' (duration: 150.250393ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:39.455231Z","caller":"traceutil/trace.go:171","msg":"trace[551498346] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2223; }","duration":"149.979936ms","start":"2026-04-28T03:21:39.305235Z","end":"2026-04-28T03:21:39.455215Z","steps":["trace[551498346] 'read index received' (duration: 144.190517ms)","trace[551498346] 'applied index is now lower than readState.Index' (duration: 5.788449ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:39.455208Z","caller":"traceutil/trace.go:171","msg":"trace[1319750789] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"149.960946ms","start":"2026-04-28T03:21:39.305211Z","end":"2026-04-28T03:21:39.455172Z","steps":["trace[1319750789] 'process raft request' (duration: 149.663309ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:39.455301Z","caller":"traceutil/trace.go:171","msg":"trace[794680361] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"148.597046ms","start":"2026-04-28T03:21:39.306696Z","end":"2026-04-28T03:21:39.455293Z","steps":["trace[794680361] 'process raft request' (duration: 148.383911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:39.455521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.256222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-28T03:21:39.455597Z","caller":"traceutil/trace.go:171","msg":"trace[1002465390] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:2132; }","duration":"150.488807ms","start":"2026-04-28T03:21:39.30509Z","end":"2026-04-28T03:21:39.455579Z","steps":["trace[1002465390] 'agreement among raft nodes before linearized reading' (duration: 150.347884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:39.456112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.072261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:115 size:88769"} {"level":"info","ts":"2026-04-28T03:21:39.456195Z","caller":"traceutil/trace.go:171","msg":"trace[764455774] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:115; response_revision:2132; }","duration":"129.207285ms","start":"2026-04-28T03:21:39.326969Z","end":"2026-04-28T03:21:39.456176Z","steps":["trace[764455774] 'agreement among raft nodes before linearized reading' (duration: 128.442777ms)"],"step_count":1}