{"level":"warn","ts":"2026-04-10T01:09:34.649097Z","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-10T01:09:34.649312Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-10T01:09:34.649387Z","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-10T01:09:34.649396Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-10T01:09:34.649413Z","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-10T01:09:34.650069Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-04-10T01:09:34.65017Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-10T01:09:34.655349Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.898553ms"} {"level":"info","ts":"2026-04-10T01:09:34.666893Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-04-10T01:09:34.666984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T01:09:34.667007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-04-10T01:09:34.66702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T01:09:34.667029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-04-10T01:09:34.667069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-04-10T01:09:34.679194Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T01:09:34.684928Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T01:09:34.685987Z","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-10T01:09:34.689052Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T01:09:34.689216Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T01:09:34.689328Z","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-10T01:09:34.68943Z","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-10T01:09:34.689439Z","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-10T01:09:34.692182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-04-10T01:09:34.692404Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-10T01:09:34.697519Z","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-10T01:09:34.69759Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-10T01:09:34.69765Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-10T01:09:34.697744Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T01:09:34.697785Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T01:09:35.367695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T01:09:35.36775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T01:09:35.367785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-04-10T01:09:35.367802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-04-10T01:09:35.367813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-10T01:09:35.367832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-04-10T01:09:35.367849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-10T01:09:35.369131Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:09:35.370125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T01:09:35.370226Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T01:09:35.370121Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T01:09:35.370456Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T01:09:35.370546Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T01:09:35.372654Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-04-10T01:09:35.373091Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:09:35.373216Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:09:35.373256Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:09:35.373581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-10T01:09:39.683608Z","caller":"traceutil/trace.go:171","msg":"trace[1483857923] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"305.057212ms","start":"2026-04-10T01:09:39.378518Z","end":"2026-04-10T01:09:39.683576Z","steps":["trace[1483857923] 'process raft request' (duration: 304.912628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:39.684257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:09:39.378497Z","time spent":"305.281298ms","remote":"127.0.0.1:51734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:09:40.003875Z","caller":"traceutil/trace.go:171","msg":"trace[1409898819] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:219; }","duration":"316.36949ms","start":"2026-04-10T01:09:39.687476Z","end":"2026-04-10T01:09:40.003846Z","steps":["trace[1409898819] 'read index received' (duration: 316.251096ms)","trace[1409898819] 'applied index is now lower than readState.Index' (duration: 116.884µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:09:40.004043Z","caller":"traceutil/trace.go:171","msg":"trace[1110231811] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"379.811822ms","start":"2026-04-10T01:09:39.624201Z","end":"2026-04-10T01:09:40.004013Z","steps":["trace[1110231811] 'process raft request' (duration: 379.493864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:40.004194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:09:39.624173Z","time spent":"379.951956ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:09:40.004193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.664338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-hiuaj6\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:09:40.004214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.346779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:09:40.004287Z","caller":"traceutil/trace.go:171","msg":"trace[374937117] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-hiuaj6; range_end:; response_count:0; response_revision:214; }","duration":"316.857613ms","start":"2026-04-10T01:09:39.687408Z","end":"2026-04-10T01:09:40.004265Z","steps":["trace[374937117] 'agreement among raft nodes before linearized reading' (duration: 316.622887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:40.004344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:09:39.687384Z","time spent":"316.947346ms","remote":"127.0.0.1:51664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-hiuaj6\" "} {"level":"info","ts":"2026-04-10T01:09:40.004292Z","caller":"traceutil/trace.go:171","msg":"trace[447831089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:214; }","duration":"176.481652ms","start":"2026-04-10T01:09:39.82779Z","end":"2026-04-10T01:09:40.004272Z","steps":["trace[447831089] 'agreement among raft nodes before linearized reading' (duration: 176.347359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:40.261959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.710965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-10T01:09:40.26202Z","caller":"traceutil/trace.go:171","msg":"trace[205262553] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:231; }","duration":"117.82832ms","start":"2026-04-10T01:09:40.144179Z","end":"2026-04-10T01:09:40.262007Z","steps":["trace[205262553] 'range keys from in-memory index tree' (duration: 117.598603ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:10.616776Z","caller":"traceutil/trace.go:171","msg":"trace[1039033511] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"164.211733ms","start":"2026-04-10T01:10:10.45252Z","end":"2026-04-10T01:10:10.616731Z","steps":["trace[1039033511] 'process raft request' (duration: 163.899953ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:10.62363Z","caller":"traceutil/trace.go:171","msg":"trace[1568010071] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"155.067386ms","start":"2026-04-10T01:10:10.468543Z","end":"2026-04-10T01:10:10.62361Z","steps":["trace[1568010071] 'process raft request' (duration: 154.89122ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:10.623846Z","caller":"traceutil/trace.go:171","msg":"trace[1076762765] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"155.215432ms","start":"2026-04-10T01:10:10.468623Z","end":"2026-04-10T01:10:10.623839Z","steps":["trace[1076762765] 'process raft request' (duration: 154.928572ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:10.624159Z","caller":"traceutil/trace.go:171","msg":"trace[266955631] transaction","detail":"{read_only:false; number_of_response:0; response_revision:505; }","duration":"151.479776ms","start":"2026-04-10T01:10:10.472661Z","end":"2026-04-10T01:10:10.624141Z","steps":["trace[266955631] 'process raft request' (duration: 151.128734ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:10.624373Z","caller":"traceutil/trace.go:171","msg":"trace[555315801] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"151.123934ms","start":"2026-04-10T01:10:10.473218Z","end":"2026-04-10T01:10:10.624342Z","steps":["trace[555315801] 'process raft request' (duration: 150.852555ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:10.624467Z","caller":"traceutil/trace.go:171","msg":"trace[568832108] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"110.51018ms","start":"2026-04-10T01:10:10.513935Z","end":"2026-04-10T01:10:10.624445Z","steps":["trace[568832108] 'process raft request' (duration: 110.433668ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:10.624465Z","caller":"traceutil/trace.go:171","msg":"trace[860146048] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"132.54471ms","start":"2026-04-10T01:10:10.491894Z","end":"2026-04-10T01:10:10.624439Z","steps":["trace[860146048] 'process raft request' (duration: 132.399625ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:10.624427Z","caller":"traceutil/trace.go:171","msg":"trace[261042840] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"151.075032ms","start":"2026-04-10T01:10:10.473309Z","end":"2026-04-10T01:10:10.624384Z","steps":["trace[261042840] 'process raft request' (duration: 150.872765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:11.395299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.992842ms","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-10T01:10:11.395636Z","caller":"traceutil/trace.go:171","msg":"trace[260848545] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:539; }","duration":"339.346394ms","start":"2026-04-10T01:10:11.056234Z","end":"2026-04-10T01:10:11.39558Z","steps":["trace[260848545] 'range keys from in-memory index tree' (duration: 338.970951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:11.396356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.775489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:10:11.3965Z","caller":"traceutil/trace.go:171","msg":"trace[1025694570] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"363.843266ms","start":"2026-04-10T01:10:11.032633Z","end":"2026-04-10T01:10:11.396476Z","steps":["trace[1025694570] 'process raft request' (duration: 48.539259ms)","trace[1025694570] 'compare' (duration: 314.607343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:10:11.396595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:11.032583Z","time spent":"363.9678ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:10:11.403004Z","caller":"traceutil/trace.go:171","msg":"trace[271710945] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"346.360369ms","start":"2026-04-10T01:10:11.056635Z","end":"2026-04-10T01:10:11.402996Z","steps":["trace[271710945] 'process raft request' (duration: 346.218585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:11.403058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:11.056608Z","time spent":"346.422961ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:10:11.4134Z","caller":"traceutil/trace.go:171","msg":"trace[1228533386] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"326.708759ms","start":"2026-04-10T01:10:11.086608Z","end":"2026-04-10T01:10:11.413316Z","steps":["trace[1228533386] 'process raft request' (duration: 325.85415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:11.413837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:11.086584Z","time spent":"327.123483ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:10:11.413994Z","caller":"traceutil/trace.go:171","msg":"trace[1923654052] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"195.108731ms","start":"2026-04-10T01:10:11.218859Z","end":"2026-04-10T01:10:11.413968Z","steps":["trace[1923654052] 'process raft request' (duration: 194.162719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:11.414328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.987765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-10T01:10:11.414371Z","caller":"traceutil/trace.go:171","msg":"trace[1725779791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"164.063898ms","start":"2026-04-10T01:10:11.250295Z","end":"2026-04-10T01:10:11.414359Z","steps":["trace[1725779791] 'agreement among raft nodes before linearized reading' (duration: 163.903012ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:11.41349Z","caller":"traceutil/trace.go:171","msg":"trace[2073825563] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:562; }","duration":"163.144287ms","start":"2026-04-10T01:10:11.250335Z","end":"2026-04-10T01:10:11.413479Z","steps":["trace[2073825563] 'read index received' (duration: 153.065659ms)","trace[2073825563] 'applied index is now lower than readState.Index' (duration: 10.077948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:10:11.414626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.849342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-10T01:10:11.414651Z","caller":"traceutil/trace.go:171","msg":"trace[417949521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"138.922844ms","start":"2026-04-10T01:10:11.275721Z","end":"2026-04-10T01:10:11.414644Z","steps":["trace[417949521] 'agreement among raft nodes before linearized reading' (duration: 138.815061ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:17.816087Z","caller":"traceutil/trace.go:171","msg":"trace[772236498] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"364.881411ms","start":"2026-04-10T01:10:17.45118Z","end":"2026-04-10T01:10:17.816062Z","steps":["trace[772236498] 'read index received' (duration: 364.306061ms)","trace[772236498] 'applied index is now lower than readState.Index' (duration: 574.41µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:10:17.816167Z","caller":"traceutil/trace.go:171","msg":"trace[1430640616] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"497.99122ms","start":"2026-04-10T01:10:17.318131Z","end":"2026-04-10T01:10:17.816122Z","steps":["trace[1430640616] 'process raft request' (duration: 497.453112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:17.816333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.132959ms","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-10T01:10:17.81636Z","caller":"traceutil/trace.go:171","msg":"trace[64247540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"365.212492ms","start":"2026-04-10T01:10:17.451141Z","end":"2026-04-10T01:10:17.816353Z","steps":["trace[64247540] 'agreement among raft nodes before linearized reading' (duration: 365.085157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:17.816385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:17.45112Z","time spent":"365.259213ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:10:17.816377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:17.318084Z","time spent":"498.171206ms","remote":"127.0.0.1:51664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:10:17.821197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.761568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T01:10:17.821286Z","caller":"traceutil/trace.go:171","msg":"trace[1466234743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"356.898303ms","start":"2026-04-10T01:10:17.464368Z","end":"2026-04-10T01:10:17.821267Z","steps":["trace[1466234743] 'agreement among raft nodes before linearized reading' (duration: 356.678815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:17.821325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:17.464348Z","time spent":"356.971295ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:10:17.82158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.087618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w68jg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-10T01:10:17.821653Z","caller":"traceutil/trace.go:171","msg":"trace[266786622] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w68jg; range_end:; response_count:1; response_revision:592; }","duration":"245.213033ms","start":"2026-04-10T01:10:17.576423Z","end":"2026-04-10T01:10:17.821636Z","steps":["trace[266786622] 'agreement among raft nodes before linearized reading' (duration: 245.088559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:17.821767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.210479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:10:17.821797Z","caller":"traceutil/trace.go:171","msg":"trace[31190447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"202.277561ms","start":"2026-04-10T01:10:17.619513Z","end":"2026-04-10T01:10:17.82179Z","steps":["trace[31190447] 'agreement among raft nodes before linearized reading' (duration: 202.2314ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:31.008415Z","caller":"traceutil/trace.go:171","msg":"trace[1642050737] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"213.701376ms","start":"2026-04-10T01:10:30.794688Z","end":"2026-04-10T01:10:31.008389Z","steps":["trace[1642050737] 'process raft request' (duration: 213.534882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:32.324313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741321486553425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:10:32.409047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.549192ms","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-10T01:10:32.409515Z","caller":"traceutil/trace.go:171","msg":"trace[184785495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:741; }","duration":"587.026975ms","start":"2026-04-10T01:10:31.822463Z","end":"2026-04-10T01:10:32.40949Z","steps":["trace[184785495] 'range keys from in-memory index tree' (duration: 586.498681ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:32.4097Z","caller":"traceutil/trace.go:171","msg":"trace[692455515] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"586.174122ms","start":"2026-04-10T01:10:31.823487Z","end":"2026-04-10T01:10:32.409662Z","steps":["trace[692455515] 'process raft request' (duration: 580.731633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:32.40989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:31.823464Z","time spent":"586.331036ms","remote":"127.0.0.1:51824","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-10T01:10:32.41331Z","caller":"traceutil/trace.go:171","msg":"trace[731036392] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:768; }","duration":"589.44025ms","start":"2026-04-10T01:10:31.823843Z","end":"2026-04-10T01:10:32.413283Z","steps":["trace[731036392] 'read index received' (duration: 580.381173ms)","trace[731036392] 'applied index is now lower than readState.Index' (duration: 9.057507ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:10:32.413381Z","caller":"traceutil/trace.go:171","msg":"trace[400215798] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"586.616183ms","start":"2026-04-10T01:10:31.826743Z","end":"2026-04-10T01:10:32.413359Z","steps":["trace[400215798] 'process raft request' (duration: 586.362757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:32.413593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:31.826721Z","time spent":"586.783928ms","remote":"127.0.0.1:53320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:10:32.413781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.991605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T01:10:32.413866Z","caller":"traceutil/trace.go:171","msg":"trace[1969563716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"139.065037ms","start":"2026-04-10T01:10:32.274777Z","end":"2026-04-10T01:10:32.413842Z","steps":["trace[1969563716] 'agreement among raft nodes before linearized reading' (duration: 138.903302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:32.413647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.970231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:10:32.414031Z","caller":"traceutil/trace.go:171","msg":"trace[1713367595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"584.365752ms","start":"2026-04-10T01:10:31.829653Z","end":"2026-04-10T01:10:32.414019Z","steps":["trace[1713367595] 'agreement among raft nodes before linearized reading' (duration: 583.943901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:32.414092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:31.829645Z","time spent":"584.435204ms","remote":"127.0.0.1:51546","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-10T01:10:32.414209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.539391ms","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-10T01:10:32.414243Z","caller":"traceutil/trace.go:171","msg":"trace[195623392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"512.606623ms","start":"2026-04-10T01:10:31.901627Z","end":"2026-04-10T01:10:32.414234Z","steps":["trace[195623392] 'agreement among raft nodes before linearized reading' (duration: 512.48804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:32.414269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:31.901583Z","time spent":"512.679015ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:10:32.414098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.313438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T01:10:32.414682Z","caller":"traceutil/trace.go:171","msg":"trace[1333807907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"507.938285ms","start":"2026-04-10T01:10:31.906734Z","end":"2026-04-10T01:10:32.414672Z","steps":["trace[1333807907] 'agreement among raft nodes before linearized reading' (duration: 507.302528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:32.414717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:31.906708Z","time spent":"507.998727ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:10:32.415086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.172478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-10T01:10:32.415128Z","caller":"traceutil/trace.go:171","msg":"trace[981454900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"591.318351ms","start":"2026-04-10T01:10:31.823799Z","end":"2026-04-10T01:10:32.415117Z","steps":["trace[981454900] 'agreement among raft nodes before linearized reading' (duration: 589.694007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:32.415158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:31.82377Z","time spent":"591.378893ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-10T01:11:10.163806Z","caller":"traceutil/trace.go:171","msg":"trace[1792739639] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"124.348055ms","start":"2026-04-10T01:11:10.03943Z","end":"2026-04-10T01:11:10.163778Z","steps":["trace[1792739639] 'process raft request' (duration: 124.211212ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:11:32.932055Z","caller":"traceutil/trace.go:171","msg":"trace[448069996] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"195.867762ms","start":"2026-04-10T01:11:32.736165Z","end":"2026-04-10T01:11:32.932033Z","steps":["trace[448069996] 'read index received' (duration: 195.656976ms)","trace[448069996] 'applied index is now lower than readState.Index' (duration: 209.886µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:11:32.932347Z","caller":"traceutil/trace.go:171","msg":"trace[1475947649] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"209.381837ms","start":"2026-04-10T01:11:32.722956Z","end":"2026-04-10T01:11:32.932337Z","steps":["trace[1475947649] 'process raft request' (duration: 208.919915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:32.932577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.393596ms","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-10T01:11:32.932616Z","caller":"traceutil/trace.go:171","msg":"trace[1596501543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"196.467498ms","start":"2026-04-10T01:11:32.736139Z","end":"2026-04-10T01:11:32.932606Z","steps":["trace[1596501543] 'agreement among raft nodes before linearized reading' (duration: 196.327704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:32.932653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.399976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-10T01:11:32.932706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.134551ms","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-10T01:11:32.932745Z","caller":"traceutil/trace.go:171","msg":"trace[377935546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1046; }","duration":"196.53908ms","start":"2026-04-10T01:11:32.736184Z","end":"2026-04-10T01:11:32.932723Z","steps":["trace[377935546] 'agreement among raft nodes before linearized reading' (duration: 196.310414ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:11:32.932773Z","caller":"traceutil/trace.go:171","msg":"trace[1087264390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"192.231804ms","start":"2026-04-10T01:11:32.740524Z","end":"2026-04-10T01:11:32.932755Z","steps":["trace[1087264390] 'agreement among raft nodes before linearized reading' (duration: 192.045129ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:11:33.043639Z","caller":"traceutil/trace.go:171","msg":"trace[93724291] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"106.941495ms","start":"2026-04-10T01:11:32.936677Z","end":"2026-04-10T01:11:33.043618Z","steps":["trace[93724291] 'process raft request' (duration: 69.84806ms)","trace[93724291] 'compare' (duration: 36.975701ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:11:33.043899Z","caller":"traceutil/trace.go:171","msg":"trace[346713328] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"104.978341ms","start":"2026-04-10T01:11:32.938892Z","end":"2026-04-10T01:11:33.04387Z","steps":["trace[346713328] 'process raft request' (duration: 104.898999ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:11:33.044121Z","caller":"traceutil/trace.go:171","msg":"trace[1808678808] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"107.1144ms","start":"2026-04-10T01:11:32.937Z","end":"2026-04-10T01:11:33.044114Z","steps":["trace[1808678808] 'process raft request' (duration: 106.595956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:00.196709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741321486554455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:12:00.697866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741321486554455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:12:01.198617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741321486554455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:12:01.620672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00008096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-10T01:12:01.620767Z","caller":"traceutil/trace.go:171","msg":"trace[305900830] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000220714s","start":"2026-04-10T01:11:59.62053Z","end":"2026-04-10T01:12:01.620751Z","steps":["trace[305900830] 'agreement among raft nodes before linearized reading' (duration: 2.000107511s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:01.620817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.620518Z","time spent":"2.000288585s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:12:01.699711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741321486554455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:12:02.155161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.870885921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T01:12:02.155234Z","caller":"traceutil/trace.go:171","msg":"trace[1423255467] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1182; }","duration":"2.871001203s","start":"2026-04-10T01:11:59.284216Z","end":"2026-04-10T01:12:02.155217Z","steps":["trace[1423255467] 'count revisions from in-memory index tree' (duration: 2.870831018s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.155287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.284204Z","time spent":"2.871069146s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-10T01:12:02.155489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099879163s","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-10T01:12:02.15563Z","caller":"traceutil/trace.go:171","msg":"trace[664774979] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1182; }","duration":"1.100030307s","start":"2026-04-10T01:12:01.055582Z","end":"2026-04-10T01:12:02.155612Z","steps":["trace[664774979] 'range keys from in-memory index tree' (duration: 1.099863162s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.155738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.037574771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T01:12:02.155765Z","caller":"traceutil/trace.go:171","msg":"trace[650681911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1182; }","duration":"3.037638163s","start":"2026-04-10T01:11:59.118119Z","end":"2026-04-10T01:12:02.155757Z","steps":["trace[650681911] 'range keys from in-memory index tree' (duration: 3.037453348s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.155783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.118099Z","time spent":"3.037679334s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T01:12:02.15579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.006098607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-10T01:12:02.155838Z","caller":"traceutil/trace.go:171","msg":"trace[374281913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"3.006180419s","start":"2026-04-10T01:11:59.149644Z","end":"2026-04-10T01:12:02.155825Z","steps":["trace[374281913] 'range keys from in-memory index tree' (duration: 3.005937013s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.155876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.149622Z","time spent":"3.0062426s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:12:02.15589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.999953052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-10T01:12:02.15596Z","caller":"traceutil/trace.go:171","msg":"trace[627704142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1182; }","duration":"3.000050215s","start":"2026-04-10T01:11:59.155894Z","end":"2026-04-10T01:12:02.155944Z","steps":["trace[627704142] 'range keys from in-memory index tree' (duration: 2.999850939s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.156019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.155888Z","time spent":"3.000121626s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:12:02.156191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.994186797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-10T01:12:02.156212Z","caller":"traceutil/trace.go:171","msg":"trace[1654009423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1182; }","duration":"2.994244409s","start":"2026-04-10T01:11:59.161962Z","end":"2026-04-10T01:12:02.156206Z","steps":["trace[1654009423] 'range keys from in-memory index tree' (duration: 2.992743999s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.156229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.161941Z","time spent":"2.99428419s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-10T01:12:02.193593Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.69449518s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T01:12:02.194303Z","caller":"traceutil/trace.go:171","msg":"trace[108979008] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"2.69527797s","start":"2026-04-10T01:11:59.498998Z","end":"2026-04-10T01:12:02.194276Z","steps":["trace[108979008] 'process raft request' (duration: 2.695100776s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.194572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.498974Z","time spent":"2.695403474s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:12:02.208394Z","caller":"traceutil/trace.go:171","msg":"trace[307388632] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"1.797242131s","start":"2026-04-10T01:12:00.411135Z","end":"2026-04-10T01:12:02.208377Z","steps":["trace[307388632] 'process raft request' (duration: 1.796522782s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.2085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:00.41111Z","time spent":"1.797332574s","remote":"127.0.0.1:51824","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-10T01:12:02.208812Z","caller":"traceutil/trace.go:171","msg":"trace[1329523454] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1231; }","duration":"2.588224721s","start":"2026-04-10T01:11:59.62057Z","end":"2026-04-10T01:12:02.208794Z","steps":["trace[1329523454] 'read index received' (duration: 2.573470045s)","trace[1329523454] 'applied index is now lower than readState.Index' (duration: 14.753886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:12:02.209679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.532501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:12:02.209716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.559371047s","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-10T01:12:02.209776Z","caller":"traceutil/trace.go:171","msg":"trace[1111546462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1188; }","duration":"2.55947531s","start":"2026-04-10T01:11:59.650286Z","end":"2026-04-10T01:12:02.209762Z","steps":["trace[1111546462] 'agreement among raft nodes before linearized reading' (duration: 2.559287145s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.209818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.650271Z","time spent":"2.559539221s","remote":"127.0.0.1:51824","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-10T01:12:02.209901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.486740769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:12:02.210008Z","caller":"traceutil/trace.go:171","msg":"trace[441479356] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1188; }","duration":"2.486886693s","start":"2026-04-10T01:11:59.723095Z","end":"2026-04-10T01:12:02.209982Z","steps":["trace[441479356] 'agreement among raft nodes before linearized reading' (duration: 2.486683487s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.210062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:59.723081Z","time spent":"2.486965975s","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-10T01:12:02.209756Z","caller":"traceutil/trace.go:171","msg":"trace[1173185621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"588.636304ms","start":"2026-04-10T01:12:01.621096Z","end":"2026-04-10T01:12:02.209733Z","steps":["trace[1173185621] 'agreement among raft nodes before linearized reading' (duration: 588.517591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:02.211212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:01.621083Z","time spent":"590.117593ms","remote":"127.0.0.1:51558","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-10T01:12:08.539858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741321486554533,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T01:12:08.783521Z","caller":"traceutil/trace.go:171","msg":"trace[747676783] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"816.075513ms","start":"2026-04-10T01:12:07.967423Z","end":"2026-04-10T01:12:08.783499Z","steps":["trace[747676783] 'process raft request' (duration: 815.95835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:08.783629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:07.967403Z","time spent":"816.176235ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:12:09.320294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.819022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:12:09.320386Z","caller":"traceutil/trace.go:171","msg":"trace[1770713735] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"1.280943368s","start":"2026-04-10T01:12:08.039426Z","end":"2026-04-10T01:12:09.32037Z","steps":["trace[1770713735] 'read index received' (duration: 743.986129ms)","trace[1770713735] 'applied index is now lower than readState.Index' (duration: 536.955768ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:12:09.320474Z","caller":"traceutil/trace.go:171","msg":"trace[1757312179] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"1.091352314s","start":"2026-04-10T01:12:08.22911Z","end":"2026-04-10T01:12:09.320463Z","steps":["trace[1757312179] 'process raft request' (duration: 594.295096ms)","trace[1757312179] 'compare' (duration: 496.0182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:12:09.320533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.229083Z","time spent":"1.091420466s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:12:09.320725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281278716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:12:09.3208Z","caller":"traceutil/trace.go:171","msg":"trace[2112391226] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1233; }","duration":"1.28138622s","start":"2026-04-10T01:12:08.039395Z","end":"2026-04-10T01:12:09.320781Z","steps":["trace[2112391226] 'agreement among raft nodes before linearized reading' (duration: 1.281274537s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:09.320816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053035296s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-10T01:12:09.320845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.03938Z","time spent":"1.281455781s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-10T01:12:09.320849Z","caller":"traceutil/trace.go:171","msg":"trace[1100341407] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1233; }","duration":"1.053066847s","start":"2026-04-10T01:12:08.267772Z","end":"2026-04-10T01:12:09.320839Z","steps":["trace[1100341407] 'agreement among raft nodes before linearized reading' (duration: 1.052947754s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:09.320816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086635237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-10T01:12:09.320878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.696092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:12:09.320885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.26773Z","time spent":"1.05314806s","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"info","ts":"2026-04-10T01:12:09.320909Z","caller":"traceutil/trace.go:171","msg":"trace[1975503261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"699.802995ms","start":"2026-04-10T01:12:08.621096Z","end":"2026-04-10T01:12:09.320899Z","steps":["trace[1975503261] 'agreement among raft nodes before linearized reading' (duration: 699.671061ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:09.32091Z","caller":"traceutil/trace.go:171","msg":"trace[271951803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1233; }","duration":"1.086794232s","start":"2026-04-10T01:12:08.2341Z","end":"2026-04-10T01:12:09.320894Z","steps":["trace[271951803] 'agreement among raft nodes before linearized reading' (duration: 1.086574686s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:09.320958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.621058Z","time spent":"699.878307ms","remote":"127.0.0.1:51546","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-10T01:12:09.320984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.234083Z","time spent":"1.086891195s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:12:09.321187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080071321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-10T01:12:09.321207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085921318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T01:12:09.321239Z","caller":"traceutil/trace.go:171","msg":"trace[2016598837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1233; }","duration":"1.08597309s","start":"2026-04-10T01:12:08.235257Z","end":"2026-04-10T01:12:09.32123Z","steps":["trace[2016598837] 'agreement among raft nodes before linearized reading' (duration: 1.085865047s)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:09.321243Z","caller":"traceutil/trace.go:171","msg":"trace[147106780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"1.080142874s","start":"2026-04-10T01:12:08.241085Z","end":"2026-04-10T01:12:09.321228Z","steps":["trace[147106780] 'agreement among raft nodes before linearized reading' (duration: 1.080038021s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:09.321274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.235249Z","time spent":"1.086017981s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T01:12:09.321294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.241071Z","time spent":"1.080209915s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:12:09.321202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.66597ms","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-10T01:12:09.321463Z","caller":"traceutil/trace.go:171","msg":"trace[1020238004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"988.967538ms","start":"2026-04-10T01:12:08.332478Z","end":"2026-04-10T01:12:09.321446Z","steps":["trace[1020238004] 'agreement among raft nodes before linearized reading' (duration: 988.378993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:09.321532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.332459Z","time spent":"989.060181ms","remote":"127.0.0.1:51824","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-10T01:12:09.321393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086260418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-10T01:12:09.321627Z","caller":"traceutil/trace.go:171","msg":"trace[918100319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"1.086513484s","start":"2026-04-10T01:12:08.235097Z","end":"2026-04-10T01:12:09.32161Z","steps":["trace[918100319] 'agreement among raft nodes before linearized reading' (duration: 1.086134074s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:09.321674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:08.235082Z","time spent":"1.086578956s","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-10T01:12:11.61945Z","caller":"traceutil/trace.go:171","msg":"trace[75793703] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"133.582212ms","start":"2026-04-10T01:12:11.485845Z","end":"2026-04-10T01:12:11.619427Z","steps":["trace[75793703] 'process raft request' (duration: 133.427578ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:13.77874Z","caller":"traceutil/trace.go:171","msg":"trace[774220533] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"352.887153ms","start":"2026-04-10T01:12:13.425824Z","end":"2026-04-10T01:12:13.778711Z","steps":["trace[774220533] 'read index received' (duration: 352.75899ms)","trace[774220533] 'applied index is now lower than readState.Index' (duration: 127.393µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:12:13.77879Z","caller":"traceutil/trace.go:171","msg":"trace[563608192] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"386.847553ms","start":"2026-04-10T01:12:13.391914Z","end":"2026-04-10T01:12:13.778761Z","steps":["trace[563608192] 'process raft request' (duration: 386.622567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:13.778991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:13.391893Z","time spent":"386.966166ms","remote":"127.0.0.1:53320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11231,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:12:13.779058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.196651ms","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-10T01:12:13.779181Z","caller":"traceutil/trace.go:171","msg":"trace[1664386185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1279; }","duration":"353.359336ms","start":"2026-04-10T01:12:13.425792Z","end":"2026-04-10T01:12:13.779151Z","steps":["trace[1664386185] 'agreement among raft nodes before linearized reading' (duration: 353.046657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:13.779279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:13.425779Z","time spent":"353.484659ms","remote":"127.0.0.1:51824","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-10T01:12:13.782124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.752276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T01:12:13.78212Z","caller":"traceutil/trace.go:171","msg":"trace[1878116644] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"125.823574ms","start":"2026-04-10T01:12:13.656273Z","end":"2026-04-10T01:12:13.782097Z","steps":["trace[1878116644] 'process raft request' (duration: 125.632829ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:13.782174Z","caller":"traceutil/trace.go:171","msg":"trace[1427722126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"353.812917ms","start":"2026-04-10T01:12:13.428348Z","end":"2026-04-10T01:12:13.782161Z","steps":["trace[1427722126] 'agreement among raft nodes before linearized reading' (duration: 353.645953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:13.782204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:13.428342Z","time spent":"353.854818ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:12:13.782389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.863572ms","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-10T01:12:13.782413Z","caller":"traceutil/trace.go:171","msg":"trace[1643571163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"352.895723ms","start":"2026-04-10T01:12:13.429511Z","end":"2026-04-10T01:12:13.782407Z","steps":["trace[1643571163] 'agreement among raft nodes before linearized reading' (duration: 352.816511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:13.782436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:13.429506Z","time spent":"352.925074ms","remote":"127.0.0.1:51824","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-10T01:12:13.78257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.675173ms","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-10T01:12:13.782592Z","caller":"traceutil/trace.go:171","msg":"trace[173143105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"353.704865ms","start":"2026-04-10T01:12:13.428881Z","end":"2026-04-10T01:12:13.782586Z","steps":["trace[173143105] 'agreement among raft nodes before linearized reading' (duration: 353.639573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:13.782611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:13.428878Z","time spent":"353.728305ms","remote":"127.0.0.1:51824","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-10T01:12:13.78287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.022553ms","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-10T01:12:13.782964Z","caller":"traceutil/trace.go:171","msg":"trace[2046192393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"354.128826ms","start":"2026-04-10T01:12:13.428812Z","end":"2026-04-10T01:12:13.782941Z","steps":["trace[2046192393] 'agreement among raft nodes before linearized reading' (duration: 353.815208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:13.783022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:13.428797Z","time spent":"354.214028ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-10T01:12:17.982999Z","caller":"traceutil/trace.go:171","msg":"trace[205052130] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"179.924395ms","start":"2026-04-10T01:12:17.803053Z","end":"2026-04-10T01:12:17.982977Z","steps":["trace[205052130] 'process raft request' (duration: 179.708549ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:17.99212Z","caller":"traceutil/trace.go:171","msg":"trace[1758583843] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"188.867504ms","start":"2026-04-10T01:12:17.803232Z","end":"2026-04-10T01:12:17.992099Z","steps":["trace[1758583843] 'process raft request' (duration: 188.69562ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:17.992371Z","caller":"traceutil/trace.go:171","msg":"trace[36513395] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1350; }","duration":"189.07776ms","start":"2026-04-10T01:12:17.80328Z","end":"2026-04-10T01:12:17.992358Z","steps":["trace[36513395] 'read index received' (duration: 179.531994ms)","trace[36513395] 'applied index is now lower than readState.Index' (duration: 9.544866ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:12:17.99239Z","caller":"traceutil/trace.go:171","msg":"trace[1799098595] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"187.317442ms","start":"2026-04-10T01:12:17.805047Z","end":"2026-04-10T01:12:17.992365Z","steps":["trace[1799098595] 'process raft request' (duration: 187.016684ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:17.99239Z","caller":"traceutil/trace.go:171","msg":"trace[613672715] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"186.905231ms","start":"2026-04-10T01:12:17.805462Z","end":"2026-04-10T01:12:17.992368Z","steps":["trace[613672715] 'process raft request' (duration: 186.830809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:17.992508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.207114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T01:12:17.992546Z","caller":"traceutil/trace.go:171","msg":"trace[1510905722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"189.268035ms","start":"2026-04-10T01:12:17.803267Z","end":"2026-04-10T01:12:17.992535Z","steps":["trace[1510905722] 'agreement among raft nodes before linearized reading' (duration: 189.142792ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:48.270232Z","caller":"traceutil/trace.go:171","msg":"trace[1537636481] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"164.771528ms","start":"2026-04-10T01:12:48.105436Z","end":"2026-04-10T01:12:48.270208Z","steps":["trace[1537636481] 'read index received' (duration: 164.571853ms)","trace[1537636481] 'applied index is now lower than readState.Index' (duration: 198.465µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:12:48.270578Z","caller":"traceutil/trace.go:171","msg":"trace[190348635] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"166.366792ms","start":"2026-04-10T01:12:48.104193Z","end":"2026-04-10T01:12:48.27056Z","steps":["trace[190348635] 'process raft request' (duration: 165.870508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:48.27102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.558029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-10T01:12:48.271094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.837426ms","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-10T01:12:48.271143Z","caller":"traceutil/trace.go:171","msg":"trace[77904404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1438; }","duration":"159.920728ms","start":"2026-04-10T01:12:48.111211Z","end":"2026-04-10T01:12:48.271131Z","steps":["trace[77904404] 'agreement among raft nodes before linearized reading' (duration: 159.785704ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:12:48.271208Z","caller":"traceutil/trace.go:171","msg":"trace[1402473362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1438; }","duration":"165.712834ms","start":"2026-04-10T01:12:48.105407Z","end":"2026-04-10T01:12:48.27112Z","steps":["trace[1402473362] 'agreement among raft nodes before linearized reading' (duration: 165.441786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:48.271551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.284614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-10T01:12:48.271589Z","caller":"traceutil/trace.go:171","msg":"trace[470643573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1438; }","duration":"155.351496ms","start":"2026-04-10T01:12:48.116227Z","end":"2026-04-10T01:12:48.271578Z","steps":["trace[470643573] 'agreement among raft nodes before linearized reading' (duration: 155.273524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:48.271738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.996891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-10T01:12:48.271788Z","caller":"traceutil/trace.go:171","msg":"trace[204549896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1438; }","duration":"160.069822ms","start":"2026-04-10T01:12:48.111706Z","end":"2026-04-10T01:12:48.271776Z","steps":["trace[204549896] 'agreement among raft nodes before linearized reading' (duration: 159.958069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:52.121375Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741321486555046,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T01:12:52.22278Z","caller":"traceutil/trace.go:171","msg":"trace[1791100297] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"813.828232ms","start":"2026-04-10T01:12:51.408926Z","end":"2026-04-10T01:12:52.222754Z","steps":["trace[1791100297] 'process raft request' (duration: 813.684128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:52.222959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:51.408911Z","time spent":"813.950716ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:12:52.229184Z","caller":"traceutil/trace.go:171","msg":"trace[411101226] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1512; }","duration":"608.310782ms","start":"2026-04-10T01:12:51.620847Z","end":"2026-04-10T01:12:52.229157Z","steps":["trace[411101226] 'read index received' (duration: 601.710565ms)","trace[411101226] 'applied index is now lower than readState.Index' (duration: 6.599177ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:12:52.229481Z","caller":"traceutil/trace.go:171","msg":"trace[134655728] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"749.806145ms","start":"2026-04-10T01:12:51.479662Z","end":"2026-04-10T01:12:52.229468Z","steps":["trace[134655728] 'process raft request' (duration: 749.424605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:52.229567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:51.479634Z","time spent":"749.880367ms","remote":"127.0.0.1:51824","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-10T01:12:52.229722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.884049ms","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:535"} {"level":"info","ts":"2026-04-10T01:12:52.229763Z","caller":"traceutil/trace.go:171","msg":"trace[1548099422] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1455; }","duration":"527.152366ms","start":"2026-04-10T01:12:51.702601Z","end":"2026-04-10T01:12:52.229754Z","steps":["trace[1548099422] 'agreement among raft nodes before linearized reading' (duration: 526.845568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:52.229781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:51.702585Z","time spent":"527.191277ms","remote":"127.0.0.1:51824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-10T01:12:52.229839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.99443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:12:52.229851Z","caller":"traceutil/trace.go:171","msg":"trace[750406911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1455; }","duration":"609.085382ms","start":"2026-04-10T01:12:51.620762Z","end":"2026-04-10T01:12:52.229847Z","steps":["trace[750406911] 'agreement among raft nodes before linearized reading' (duration: 609.060902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:12:52.229865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:12:51.620744Z","time spent":"609.116093ms","remote":"127.0.0.1:51546","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-10T01:12:52.374705Z","caller":"traceutil/trace.go:171","msg":"trace[1609543289] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"141.576236ms","start":"2026-04-10T01:12:52.233113Z","end":"2026-04-10T01:12:52.374689Z","steps":["trace[1609543289] 'process raft request' (duration: 141.226887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:24.530799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.190927ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-10T01:15:24.530915Z","caller":"traceutil/trace.go:171","msg":"trace[124479840] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2264; }","duration":"209.161106ms","start":"2026-04-10T01:15:24.321738Z","end":"2026-04-10T01:15:24.5309Z","steps":["trace[124479840] 'read index received' (duration: 34.101µs)","trace[124479840] 'applied index is now lower than readState.Index' (duration: 209.125145ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:15:24.53102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.27935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:15:24.531045Z","caller":"traceutil/trace.go:171","msg":"trace[1576082253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2174; }","duration":"209.348932ms","start":"2026-04-10T01:15:24.321689Z","end":"2026-04-10T01:15:24.531038Z","steps":["trace[1576082253] 'agreement among raft nodes before linearized reading' (duration: 209.28734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:24.531219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.402935ms","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-10T01:15:24.531365Z","caller":"traceutil/trace.go:171","msg":"trace[745333591] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2174; }","duration":"138.58536ms","start":"2026-04-10T01:15:24.392758Z","end":"2026-04-10T01:15:24.531344Z","steps":["trace[745333591] 'agreement among raft nodes before linearized reading' (duration: 138.319703ms)"],"step_count":1}