{"level":"warn","ts":"2026-04-13T18:26:10.687193Z","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-13T18:26:10.68731Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-13T18:26:10.687396Z","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-13T18:26:10.687408Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-13T18:26:10.687428Z","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-13T18:26:10.68828Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-13T18:26:10.688384Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-13T18:26:10.692878Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.237229ms"} {"level":"info","ts":"2026-04-13T18:26:10.701746Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-13T18:26:10.701832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T18:26:10.701856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-13T18:26:10.701876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T18:26:10.701885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-13T18:26:10.701924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-13T18:26:10.712909Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T18:26:10.714597Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T18:26:10.716482Z","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-13T18:26:10.718909Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T18:26:10.719197Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T18:26:10.719301Z","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-13T18:26:10.719888Z","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-13T18:26:10.719916Z","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-13T18:26:10.72015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-13T18:26:10.720346Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-13T18:26:10.722807Z","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-13T18:26:10.723078Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T18:26:10.723023Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T18:26:10.722882Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-13T18:26:10.723716Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-13T18:26:11.70236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T18:26:11.702448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T18:26:11.702477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-13T18:26:11.702497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-13T18:26:11.702509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-13T18:26:11.702525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-13T18:26:11.702539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-13T18:26:11.703371Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T18:26:11.704049Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T18:26:11.704058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T18:26:11.704218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T18:26:11.70442Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T18:26:11.704458Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T18:26:11.704609Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T18:26:11.704701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T18:26:11.704728Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T18:26:11.706936Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-04-13T18:26:11.707445Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T18:26:31.298957Z","caller":"traceutil/trace.go:171","msg":"trace[586678410] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"123.016775ms","start":"2026-04-13T18:26:31.17592Z","end":"2026-04-13T18:26:31.298936Z","steps":["trace[586678410] 'process raft request' (duration: 122.840801ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.952931Z","caller":"traceutil/trace.go:171","msg":"trace[591656198] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"144.90359ms","start":"2026-04-13T18:26:34.807966Z","end":"2026-04-13T18:26:34.95287Z","steps":["trace[591656198] 'process raft request' (duration: 144.727216ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.959166Z","caller":"traceutil/trace.go:171","msg":"trace[1753268315] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"148.472444ms","start":"2026-04-13T18:26:34.810681Z","end":"2026-04-13T18:26:34.959153Z","steps":["trace[1753268315] 'process raft request' (duration: 147.731907ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.959193Z","caller":"traceutil/trace.go:171","msg":"trace[1099610450] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"134.941575ms","start":"2026-04-13T18:26:34.824215Z","end":"2026-04-13T18:26:34.959156Z","steps":["trace[1099610450] 'process raft request' (duration: 134.402773ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.959351Z","caller":"traceutil/trace.go:171","msg":"trace[1569171400] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"135.011378ms","start":"2026-04-13T18:26:34.82432Z","end":"2026-04-13T18:26:34.959332Z","steps":["trace[1569171400] 'process raft request' (duration: 134.413343ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.959402Z","caller":"traceutil/trace.go:171","msg":"trace[184473305] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"146.723673ms","start":"2026-04-13T18:26:34.812672Z","end":"2026-04-13T18:26:34.959395Z","steps":["trace[184473305] 'process raft request' (duration: 145.896544ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.959459Z","caller":"traceutil/trace.go:171","msg":"trace[53823026] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"130.46006ms","start":"2026-04-13T18:26:34.828994Z","end":"2026-04-13T18:26:34.959454Z","steps":["trace[53823026] 'process raft request' (duration: 129.907377ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.959465Z","caller":"traceutil/trace.go:171","msg":"trace[1498365113] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"135.096449ms","start":"2026-04-13T18:26:34.824356Z","end":"2026-04-13T18:26:34.959453Z","steps":["trace[1498365113] 'process raft request' (duration: 134.437094ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.959512Z","caller":"traceutil/trace.go:171","msg":"trace[227050479] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"134.796892ms","start":"2026-04-13T18:26:34.824709Z","end":"2026-04-13T18:26:34.959506Z","steps":["trace[227050479] 'process raft request' (duration: 134.136666ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:34.95956Z","caller":"traceutil/trace.go:171","msg":"trace[2065854448] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"123.927697ms","start":"2026-04-13T18:26:34.835622Z","end":"2026-04-13T18:26:34.95955Z","steps":["trace[2065854448] 'process raft request' (duration: 123.364733ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:37.576291Z","caller":"traceutil/trace.go:171","msg":"trace[713147155] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"154.360312ms","start":"2026-04-13T18:26:37.421903Z","end":"2026-04-13T18:26:37.576264Z","steps":["trace[713147155] 'read index received' (duration: 154.2809ms)","trace[713147155] 'applied index is now lower than readState.Index' (duration: 78.322µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:26:37.576569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.28707ms","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-13T18:26:37.576503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.563708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:26:37.576637Z","caller":"traceutil/trace.go:171","msg":"trace[1256234407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"117.404293ms","start":"2026-04-13T18:26:37.459219Z","end":"2026-04-13T18:26:37.576623Z","steps":["trace[1256234407] 'agreement among raft nodes before linearized reading' (duration: 117.243459ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:37.576671Z","caller":"traceutil/trace.go:171","msg":"trace[2132650934] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:575; }","duration":"154.787253ms","start":"2026-04-13T18:26:37.421858Z","end":"2026-04-13T18:26:37.576646Z","steps":["trace[2132650934] 'agreement among raft nodes before linearized reading' (duration: 154.553708ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:37.576358Z","caller":"traceutil/trace.go:171","msg":"trace[894347751] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"248.654962ms","start":"2026-04-13T18:26:37.327654Z","end":"2026-04-13T18:26:37.576309Z","steps":["trace[894347751] 'process raft request' (duration: 248.478618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:40.133188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.818547ms","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-13T18:26:40.133398Z","caller":"traceutil/trace.go:171","msg":"trace[1348755827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"436.105973ms","start":"2026-04-13T18:26:39.697234Z","end":"2026-04-13T18:26:40.13334Z","steps":["trace[1348755827] 'range keys from in-memory index tree' (duration: 435.56578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:40.133452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:39.697215Z","time spent":"436.223735ms","remote":"127.0.0.1:51194","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-13T18:26:40.138669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.522221ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-13T18:26:40.138852Z","caller":"traceutil/trace.go:171","msg":"trace[1161952416] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"260.027379ms","start":"2026-04-13T18:26:39.878806Z","end":"2026-04-13T18:26:40.138834Z","steps":["trace[1161952416] 'read index received' (duration: 118.225672ms)","trace[1161952416] 'applied index is now lower than readState.Index' (duration: 141.799677ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:26:40.139009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.188943ms","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-13T18:26:40.139037Z","caller":"traceutil/trace.go:171","msg":"trace[295866532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"260.275045ms","start":"2026-04-13T18:26:39.878755Z","end":"2026-04-13T18:26:40.13903Z","steps":["trace[295866532] 'agreement among raft nodes before linearized reading' (duration: 260.148342ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:40.139355Z","caller":"traceutil/trace.go:171","msg":"trace[1337571958] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"211.578049ms","start":"2026-04-13T18:26:39.927768Z","end":"2026-04-13T18:26:40.139346Z","steps":["trace[1337571958] 'process raft request' (duration: 211.111718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:41.800304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.301889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:26:41.800352Z","caller":"traceutil/trace.go:171","msg":"trace[1716586106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"312.400662ms","start":"2026-04-13T18:26:41.487942Z","end":"2026-04-13T18:26:41.800343Z","steps":["trace[1716586106] 'agreement among raft nodes before linearized reading' (duration: 312.31878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:41.800377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:41.487892Z","time spent":"312.480263ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T18:26:41.800225Z","caller":"traceutil/trace.go:171","msg":"trace[908938875] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"666.253638ms","start":"2026-04-13T18:26:41.133954Z","end":"2026-04-13T18:26:41.800207Z","steps":["trace[908938875] 'process raft request' (duration: 665.839219ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:26:41.800756Z","caller":"traceutil/trace.go:171","msg":"trace[1491305126] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"312.177976ms","start":"2026-04-13T18:26:41.48799Z","end":"2026-04-13T18:26:41.800168Z","steps":["trace[1491305126] 'read index received' (duration: 311.547562ms)","trace[1491305126] 'applied index is now lower than readState.Index' (duration: 629.744µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:26:41.800916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.802987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j6wvj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-13T18:26:41.800955Z","caller":"traceutil/trace.go:171","msg":"trace[1711476420] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j6wvj; range_end:; response_count:1; response_revision:600; }","duration":"240.870119ms","start":"2026-04-13T18:26:41.560074Z","end":"2026-04-13T18:26:41.800944Z","steps":["trace[1711476420] 'agreement among raft nodes before linearized reading' (duration: 240.781957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:41.800984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.917377ms","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-13T18:26:41.801017Z","caller":"traceutil/trace.go:171","msg":"trace[1356057339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"194.993619ms","start":"2026-04-13T18:26:41.606012Z","end":"2026-04-13T18:26:41.801006Z","steps":["trace[1356057339] 'agreement among raft nodes before linearized reading' (duration: 194.929918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:41.80111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.656737ms","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-13T18:26:41.801214Z","caller":"traceutil/trace.go:171","msg":"trace[2023902056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"213.804911ms","start":"2026-04-13T18:26:41.587378Z","end":"2026-04-13T18:26:41.801182Z","steps":["trace[2023902056] 'agreement among raft nodes before linearized reading' (duration: 213.572576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:41.801272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:41.133931Z","time spent":"666.683479ms","remote":"127.0.0.1:51058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:26:43.210796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228270645s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T18:26:43.21097Z","caller":"traceutil/trace.go:171","msg":"trace[559903297] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"1.062612356s","start":"2026-04-13T18:26:42.148338Z","end":"2026-04-13T18:26:43.210951Z","steps":["trace[559903297] 'read index received' (duration: 43.201µs)","trace[559903297] 'applied index is now lower than readState.Index' (duration: 1.062568005s)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:26:43.211352Z","caller":"traceutil/trace.go:171","msg":"trace[1477401617] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.3589216s","start":"2026-04-13T18:26:41.852389Z","end":"2026-04-13T18:26:43.211311Z","steps":["trace[1477401617] 'process raft request' (duration: 1.35850016s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:43.211418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063082677s","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-13T18:26:43.211458Z","caller":"traceutil/trace.go:171","msg":"trace[1690596862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"1.063122018s","start":"2026-04-13T18:26:42.148323Z","end":"2026-04-13T18:26:43.211445Z","steps":["trace[1690596862] 'agreement among raft nodes before linearized reading' (duration: 1.062982495s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:43.211467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:41.852371Z","time spent":"1.359041302s","remote":"127.0.0.1:51420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T18:26:43.211544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057731422s","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-13T18:26:43.211565Z","caller":"traceutil/trace.go:171","msg":"trace[967864783] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.394437106s","start":"2026-04-13T18:26:41.817118Z","end":"2026-04-13T18:26:43.211555Z","steps":["trace[967864783] 'process raft request' (duration: 165.325391ms)","trace[967864783] 'compare' (duration: 1.228099011s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:26:43.211564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:42.14826Z","time spent":"1.063292663s","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T18:26:43.211604Z","caller":"traceutil/trace.go:171","msg":"trace[169381018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"1.057811133s","start":"2026-04-13T18:26:42.153773Z","end":"2026-04-13T18:26:43.211584Z","steps":["trace[169381018] 'agreement among raft nodes before linearized reading' (duration: 1.057704341s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:43.211612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:41.817093Z","time spent":"1.394494126s","remote":"127.0.0.1:51130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:26:43.21164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:42.153762Z","time spent":"1.057869745s","remote":"127.0.0.1:51194","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-13T18:26:43.211814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.524777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:26:43.211882Z","caller":"traceutil/trace.go:171","msg":"trace[599747012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"726.64019ms","start":"2026-04-13T18:26:42.485225Z","end":"2026-04-13T18:26:43.211865Z","steps":["trace[599747012] 'agreement among raft nodes before linearized reading' (duration: 726.505977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:43.211924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:42.485206Z","time spent":"726.711022ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T18:26:44.98727Z","caller":"traceutil/trace.go:171","msg":"trace[891639114] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"134.039494ms","start":"2026-04-13T18:26:44.853207Z","end":"2026-04-13T18:26:44.987247Z","steps":["trace[891639114] 'process raft request' (duration: 133.920251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:57.94455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.935973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:26:57.944598Z","caller":"traceutil/trace.go:171","msg":"trace[1155388207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"457.023735ms","start":"2026-04-13T18:26:57.487564Z","end":"2026-04-13T18:26:57.944588Z","steps":["trace[1155388207] 'range keys from in-memory index tree' (duration: 456.780579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:26:57.944616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:26:57.487546Z","time spent":"457.065686ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T18:26:57.944862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.734502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-phc66\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-13T18:26:57.944882Z","caller":"traceutil/trace.go:171","msg":"trace[1186321944] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-phc66; range_end:; response_count:1; response_revision:767; }","duration":"290.815694ms","start":"2026-04-13T18:26:57.65406Z","end":"2026-04-13T18:26:57.944876Z","steps":["trace[1186321944] 'range keys from in-memory index tree' (duration: 290.61957ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:27:13.504155Z","caller":"traceutil/trace.go:171","msg":"trace[19823614] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"116.659965ms","start":"2026-04-13T18:27:13.387473Z","end":"2026-04-13T18:27:13.504133Z","steps":["trace[19823614] 'process raft request' (duration: 104.258394ms)","trace[19823614] 'compare' (duration: 12.314259ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:27:18.73762Z","caller":"traceutil/trace.go:171","msg":"trace[1011798735] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"114.255398ms","start":"2026-04-13T18:27:18.623336Z","end":"2026-04-13T18:27:18.737592Z","steps":["trace[1011798735] 'process raft request' (duration: 114.075374ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:27:33.751169Z","caller":"traceutil/trace.go:171","msg":"trace[1589720799] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"148.994387ms","start":"2026-04-13T18:27:33.60215Z","end":"2026-04-13T18:27:33.751144Z","steps":["trace[1589720799] 'read index received' (duration: 148.847373ms)","trace[1589720799] 'applied index is now lower than readState.Index' (duration: 146.034µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:27:33.751197Z","caller":"traceutil/trace.go:171","msg":"trace[1261567451] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"232.509111ms","start":"2026-04-13T18:27:33.518663Z","end":"2026-04-13T18:27:33.751172Z","steps":["trace[1261567451] 'process raft request' (duration: 232.333566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:33.751441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.287104ms","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-13T18:27:33.751487Z","caller":"traceutil/trace.go:171","msg":"trace[312405085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"149.368386ms","start":"2026-04-13T18:27:33.602109Z","end":"2026-04-13T18:27:33.751477Z","steps":["trace[312405085] 'agreement among raft nodes before linearized reading' (duration: 149.210582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:34.431379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.594683ms","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-13T18:27:34.4315Z","caller":"traceutil/trace.go:171","msg":"trace[2138311784] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:982; }","duration":"540.727736ms","start":"2026-04-13T18:27:33.890755Z","end":"2026-04-13T18:27:34.431482Z","steps":["trace[2138311784] 'range keys from in-memory index tree' (duration: 540.579983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:34.701092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743992399238688,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T18:27:34.827594Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.02771667s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T18:27:34.827948Z","caller":"traceutil/trace.go:171","msg":"trace[396129850] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"627.406296ms","start":"2026-04-13T18:27:34.200519Z","end":"2026-04-13T18:27:34.827925Z","steps":["trace[396129850] 'read index received' (duration: 627.248533ms)","trace[396129850] 'applied index is now lower than readState.Index' (duration: 156.783µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:27:34.828087Z","caller":"traceutil/trace.go:171","msg":"trace[173484372] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.072741965s","start":"2026-04-13T18:27:33.755331Z","end":"2026-04-13T18:27:34.828073Z","steps":["trace[173484372] 'process raft request' (duration: 1.072465578s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:34.828225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.066231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-13T18:27:34.828221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:33.755315Z","time spent":"1.072821267s","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:27:34.828261Z","caller":"traceutil/trace.go:171","msg":"trace[1689155875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"620.111532ms","start":"2026-04-13T18:27:34.20814Z","end":"2026-04-13T18:27:34.828251Z","steps":["trace[1689155875] 'agreement among raft nodes before linearized reading' (duration: 619.988629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:34.828291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:34.208081Z","time spent":"620.196144ms","remote":"127.0.0.1:51194","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-13T18:27:34.829363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.766552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-13T18:27:34.82938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.868191ms","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-13T18:27:34.829418Z","caller":"traceutil/trace.go:171","msg":"trace[2082985502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"628.941503ms","start":"2026-04-13T18:27:34.20047Z","end":"2026-04-13T18:27:34.829412Z","steps":["trace[2082985502] 'agreement among raft nodes before linearized reading' (duration: 628.865151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:34.829437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:34.20045Z","time spent":"628.982423ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T18:27:34.829426Z","caller":"traceutil/trace.go:171","msg":"trace[377862503] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:983; }","duration":"355.858284ms","start":"2026-04-13T18:27:34.473552Z","end":"2026-04-13T18:27:34.82941Z","steps":["trace[377862503] 'agreement among raft nodes before linearized reading' (duration: 354.879601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:34.829483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:34.473533Z","time spent":"355.938656ms","remote":"127.0.0.1:50984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-04-13T18:27:35.240923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.424926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T18:27:35.241116Z","caller":"traceutil/trace.go:171","msg":"trace[565731872] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"354.779338ms","start":"2026-04-13T18:27:34.886313Z","end":"2026-04-13T18:27:35.241092Z","steps":["trace[565731872] 'process raft request' (duration: 217.890601ms)","trace[565731872] 'compare' (duration: 136.255912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:27:35.24121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:34.886295Z","time spent":"354.87207ms","remote":"127.0.0.1:50984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:27:35.257523Z","caller":"traceutil/trace.go:171","msg":"trace[1503880597] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"367.624825ms","start":"2026-04-13T18:27:34.889861Z","end":"2026-04-13T18:27:35.257485Z","steps":["trace[1503880597] 'process raft request' (duration: 367.460221ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:27:35.257563Z","caller":"traceutil/trace.go:171","msg":"trace[925898989] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"306.866985ms","start":"2026-04-13T18:27:34.950673Z","end":"2026-04-13T18:27:35.25754Z","steps":["trace[925898989] 'process raft request' (duration: 306.741452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:35.257658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:34.889839Z","time spent":"367.738668ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:27:35.257676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:34.950652Z","time spent":"306.964457ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:27:35.258473Z","caller":"traceutil/trace.go:171","msg":"trace[1009866802] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"245.373577ms","start":"2026-04-13T18:27:35.01309Z","end":"2026-04-13T18:27:35.258463Z","steps":["trace[1009866802] 'process raft request' (duration: 244.382473ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:27:56.500865Z","caller":"traceutil/trace.go:171","msg":"trace[1788170378] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"396.555486ms","start":"2026-04-13T18:27:56.104284Z","end":"2026-04-13T18:27:56.500839Z","steps":["trace[1788170378] 'read index received' (duration: 396.349411ms)","trace[1788170378] 'applied index is now lower than readState.Index' (duration: 205.165µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:27:56.50095Z","caller":"traceutil/trace.go:171","msg":"trace[2071731246] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"644.294209ms","start":"2026-04-13T18:27:55.85663Z","end":"2026-04-13T18:27:56.500924Z","steps":["trace[2071731246] 'process raft request' (duration: 644.054233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:56.501021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.716059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T18:27:56.50106Z","caller":"traceutil/trace.go:171","msg":"trace[1125029444] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1056; }","duration":"396.811012ms","start":"2026-04-13T18:27:56.104238Z","end":"2026-04-13T18:27:56.501049Z","steps":["trace[1125029444] 'agreement among raft nodes before linearized reading' (duration: 396.718769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:56.501085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:55.856608Z","time spent":"644.406902ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:27:56.501098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:27:56.104219Z","time spent":"396.868213ms","remote":"127.0.0.1:51058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-13T18:27:57.30889Z","caller":"traceutil/trace.go:171","msg":"trace[940839657] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"153.062273ms","start":"2026-04-13T18:27:57.155804Z","end":"2026-04-13T18:27:57.308866Z","steps":["trace[940839657] 'process raft request' (duration: 152.857098ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:27:58.213994Z","caller":"traceutil/trace.go:171","msg":"trace[830857787] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"164.127378ms","start":"2026-04-13T18:27:58.049832Z","end":"2026-04-13T18:27:58.213959Z","steps":["trace[830857787] 'process raft request' (duration: 163.923733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:58.721294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.43325ms","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-13T18:27:58.721376Z","caller":"traceutil/trace.go:171","msg":"trace[620365685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"217.540282ms","start":"2026-04-13T18:27:58.503808Z","end":"2026-04-13T18:27:58.721349Z","steps":["trace[620365685] 'range keys from in-memory index tree' (duration: 217.276917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:27:59.296879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.461516ms","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-13T18:27:59.296988Z","caller":"traceutil/trace.go:171","msg":"trace[856736071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"141.62272ms","start":"2026-04-13T18:27:59.155342Z","end":"2026-04-13T18:27:59.296965Z","steps":["trace[856736071] 'range keys from in-memory index tree' (duration: 141.253722ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:15.230025Z","caller":"traceutil/trace.go:171","msg":"trace[1567193651] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"225.518193ms","start":"2026-04-13T18:28:15.004484Z","end":"2026-04-13T18:28:15.230002Z","steps":["trace[1567193651] 'read index received' (duration: 225.317478ms)","trace[1567193651] 'applied index is now lower than readState.Index' (duration: 199.995µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:15.230166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.664467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T18:28:15.230188Z","caller":"traceutil/trace.go:171","msg":"trace[1392122875] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1166; }","duration":"225.730589ms","start":"2026-04-13T18:28:15.00445Z","end":"2026-04-13T18:28:15.230181Z","steps":["trace[1392122875] 'agreement among raft nodes before linearized reading' (duration: 225.667367ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:15.230171Z","caller":"traceutil/trace.go:171","msg":"trace[1007347252] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"347.868793ms","start":"2026-04-13T18:28:14.882262Z","end":"2026-04-13T18:28:15.23013Z","steps":["trace[1007347252] 'process raft request' (duration: 347.624518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:15.230284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.633052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T18:28:15.230357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:14.882239Z","time spent":"348.036118ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:28:15.230363Z","caller":"traceutil/trace.go:171","msg":"trace[283267739] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1166; }","duration":"180.785845ms","start":"2026-04-13T18:28:15.049557Z","end":"2026-04-13T18:28:15.230342Z","steps":["trace[283267739] 'agreement among raft nodes before linearized reading' (duration: 180.627252ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:15.929667Z","caller":"traceutil/trace.go:171","msg":"trace[399676342] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"695.685946ms","start":"2026-04-13T18:28:15.23397Z","end":"2026-04-13T18:28:15.929656Z","steps":["trace[399676342] 'process raft request' (duration: 623.616456ms)","trace[399676342] 'compare' (duration: 71.876115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:15.929757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:15.233954Z","time spent":"695.747317ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:28:15.929593Z","caller":"traceutil/trace.go:171","msg":"trace[456617754] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"500.313343ms","start":"2026-04-13T18:28:15.429252Z","end":"2026-04-13T18:28:15.929565Z","steps":["trace[456617754] 'read index received' (duration: 428.463588ms)","trace[456617754] 'applied index is now lower than readState.Index' (duration: 71.848865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:15.930161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.885246ms","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-13T18:28:15.93025Z","caller":"traceutil/trace.go:171","msg":"trace[821572904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1167; }","duration":"501.010679ms","start":"2026-04-13T18:28:15.429217Z","end":"2026-04-13T18:28:15.930228Z","steps":["trace[821572904] 'agreement among raft nodes before linearized reading' (duration: 500.786813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:15.930358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:15.429201Z","time spent":"501.097851ms","remote":"127.0.0.1:51194","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-13T18:28:15.962026Z","caller":"traceutil/trace.go:171","msg":"trace[1421134272] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"411.964403ms","start":"2026-04-13T18:28:15.550034Z","end":"2026-04-13T18:28:15.961998Z","steps":["trace[1421134272] 'process raft request' (duration: 411.875081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:15.962094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.939996ms","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-13T18:28:15.962141Z","caller":"traceutil/trace.go:171","msg":"trace[611934809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1169; }","duration":"516.002957ms","start":"2026-04-13T18:28:15.446126Z","end":"2026-04-13T18:28:15.962128Z","steps":["trace[611934809] 'agreement among raft nodes before linearized reading' (duration: 515.830003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:15.962148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:15.550025Z","time spent":"412.071076ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:28:15.962174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:15.446103Z","time spent":"516.063548ms","remote":"127.0.0.1:51194","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-13T18:28:15.962182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.906727ms","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-13T18:28:15.962286Z","caller":"traceutil/trace.go:171","msg":"trace[915955562] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"463.944174ms","start":"2026-04-13T18:28:15.498302Z","end":"2026-04-13T18:28:15.962246Z","steps":["trace[915955562] 'process raft request' (duration: 463.470533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:15.962345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.934815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T18:28:15.962453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:15.498285Z","time spent":"464.090008ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:28:15.962283Z","caller":"traceutil/trace.go:171","msg":"trace[858366624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1169; }","duration":"377.05298ms","start":"2026-04-13T18:28:15.585213Z","end":"2026-04-13T18:28:15.962266Z","steps":["trace[858366624] 'agreement among raft nodes before linearized reading' (duration: 376.798674ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:15.9625Z","caller":"traceutil/trace.go:171","msg":"trace[268701406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1169; }","duration":"477.126819ms","start":"2026-04-13T18:28:15.485355Z","end":"2026-04-13T18:28:15.962482Z","steps":["trace[268701406] 'agreement among raft nodes before linearized reading' (duration: 476.899673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:15.962578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:15.485343Z","time spent":"477.223502ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T18:28:15.96262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:15.585203Z","time spent":"377.364478ms","remote":"127.0.0.1:51194","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-13T18:28:29.277347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.856464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:28:29.27746Z","caller":"traceutil/trace.go:171","msg":"trace[473104995] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1212; }","duration":"191.070079ms","start":"2026-04-13T18:28:29.086367Z","end":"2026-04-13T18:28:29.277437Z","steps":["trace[473104995] 'count revisions from in-memory index tree' (duration: 190.745901ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:29.741296Z","caller":"traceutil/trace.go:171","msg":"trace[1335421406] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"184.975854ms","start":"2026-04-13T18:28:29.556297Z","end":"2026-04-13T18:28:29.741273Z","steps":["trace[1335421406] 'process raft request' (duration: 184.833511ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:29.741282Z","caller":"traceutil/trace.go:171","msg":"trace[2001426552] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"183.138591ms","start":"2026-04-13T18:28:29.558117Z","end":"2026-04-13T18:28:29.741256Z","steps":["trace[2001426552] 'read index received' (duration: 182.937356ms)","trace[2001426552] 'applied index is now lower than readState.Index' (duration: 200.405µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:29.741556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.402967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:28:29.741667Z","caller":"traceutil/trace.go:171","msg":"trace[1249228358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"183.580801ms","start":"2026-04-13T18:28:29.558065Z","end":"2026-04-13T18:28:29.741646Z","steps":["trace[1249228358] 'agreement among raft nodes before linearized reading' (duration: 183.300584ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:30.194132Z","caller":"traceutil/trace.go:171","msg":"trace[62708523] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"105.562079ms","start":"2026-04-13T18:28:30.088532Z","end":"2026-04-13T18:28:30.194094Z","steps":["trace[62708523] 'process raft request' (duration: 75.516021ms)","trace[62708523] 'compare' (duration: 29.832492ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:28:30.194341Z","caller":"traceutil/trace.go:171","msg":"trace[618422170] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"104.873473ms","start":"2026-04-13T18:28:30.089435Z","end":"2026-04-13T18:28:30.194309Z","steps":["trace[618422170] 'process raft request' (duration: 104.546125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:30.832822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.356566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T18:28:30.832906Z","caller":"traceutil/trace.go:171","msg":"trace[1966145875] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"506.986298ms","start":"2026-04-13T18:28:30.325906Z","end":"2026-04-13T18:28:30.832892Z","steps":["trace[1966145875] 'read index received' (duration: 46.395247ms)","trace[1966145875] 'applied index is now lower than readState.Index' (duration: 460.590221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:30.833016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.154781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:28:30.832992Z","caller":"traceutil/trace.go:171","msg":"trace[1228843282] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"551.343016ms","start":"2026-04-13T18:28:30.281603Z","end":"2026-04-13T18:28:30.832946Z","steps":["trace[1228843282] 'process raft request' (duration: 90.800476ms)","trace[1228843282] 'compare' (duration: 460.253393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:30.833101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.162052ms","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-13T18:28:30.833033Z","caller":"traceutil/trace.go:171","msg":"trace[1322709787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1222; }","duration":"346.212122ms","start":"2026-04-13T18:28:30.486815Z","end":"2026-04-13T18:28:30.833027Z","steps":["trace[1322709787] 'agreement among raft nodes before linearized reading' (duration: 346.147811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:30.833326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:30.486794Z","time spent":"346.507389ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T18:28:30.833347Z","caller":"traceutil/trace.go:171","msg":"trace[72607596] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1222; }","duration":"507.418468ms","start":"2026-04-13T18:28:30.32587Z","end":"2026-04-13T18:28:30.833288Z","steps":["trace[72607596] 'agreement among raft nodes before linearized reading' (duration: 507.08427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:30.833423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:30.325855Z","time spent":"507.550522ms","remote":"127.0.0.1:51312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-04-13T18:28:30.833216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:30.281572Z","time spent":"551.50529ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T18:28:32.554176Z","caller":"traceutil/trace.go:171","msg":"trace[295420574] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"557.103614ms","start":"2026-04-13T18:28:31.997055Z","end":"2026-04-13T18:28:32.554159Z","steps":["trace[295420574] 'process raft request' (duration: 556.958091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:32.55429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:31.997036Z","time spent":"557.193616ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T18:28:32.554165Z","caller":"traceutil/trace.go:171","msg":"trace[969248579] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"491.96436ms","start":"2026-04-13T18:28:32.062175Z","end":"2026-04-13T18:28:32.55414Z","steps":["trace[969248579] 'read index received' (duration: 491.901738ms)","trace[969248579] 'applied index is now lower than readState.Index' (duration: 61.822µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:32.554378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.187425ms","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-13T18:28:32.55444Z","caller":"traceutil/trace.go:171","msg":"trace[918309101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"492.291778ms","start":"2026-04-13T18:28:32.062134Z","end":"2026-04-13T18:28:32.554426Z","steps":["trace[918309101] 'agreement among raft nodes before linearized reading' (duration: 492.077483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:32.554486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:32.062116Z","time spent":"492.359169ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T18:28:32.570362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.962562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-13T18:28:32.570413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.073709ms","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-13T18:28:32.570464Z","caller":"traceutil/trace.go:171","msg":"trace[1803694584] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"481.027488ms","start":"2026-04-13T18:28:32.08943Z","end":"2026-04-13T18:28:32.570457Z","steps":["trace[1803694584] 'process raft request' (duration: 480.70064ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:32.57047Z","caller":"traceutil/trace.go:171","msg":"trace[1498364461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1225; }","duration":"372.187971ms","start":"2026-04-13T18:28:32.198266Z","end":"2026-04-13T18:28:32.570454Z","steps":["trace[1498364461] 'agreement among raft nodes before linearized reading' (duration: 372.043078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:32.570486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.411826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T18:28:32.570514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:32.198242Z","time spent":"372.261493ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T18:28:32.570524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:32.089401Z","time spent":"481.08077ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:28:32.570541Z","caller":"traceutil/trace.go:171","msg":"trace[762099723] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1225; }","duration":"144.529719ms","start":"2026-04-13T18:28:32.425994Z","end":"2026-04-13T18:28:32.570524Z","steps":["trace[762099723] 'agreement among raft nodes before linearized reading' (duration: 144.437077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:32.570534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.375665ms","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-13T18:28:32.570431Z","caller":"traceutil/trace.go:171","msg":"trace[1166570338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1225; }","duration":"454.083815ms","start":"2026-04-13T18:28:32.116333Z","end":"2026-04-13T18:28:32.570417Z","steps":["trace[1166570338] 'agreement among raft nodes before linearized reading' (duration: 453.901261ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:32.570627Z","caller":"traceutil/trace.go:171","msg":"trace[1378659355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1225; }","duration":"372.482198ms","start":"2026-04-13T18:28:32.198102Z","end":"2026-04-13T18:28:32.570584Z","steps":["trace[1378659355] 'agreement among raft nodes before linearized reading' (duration: 372.241572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:32.570649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:32.116312Z","time spent":"454.325482ms","remote":"127.0.0.1:51194","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-13T18:28:32.570684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:32.198086Z","time spent":"372.573211ms","remote":"127.0.0.1:51194","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-13T18:28:32.692398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.50505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T18:28:32.692496Z","caller":"traceutil/trace.go:171","msg":"trace[1018103943] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"133.182598ms","start":"2026-04-13T18:28:32.559295Z","end":"2026-04-13T18:28:32.692478Z","steps":["trace[1018103943] 'process raft request' (duration: 16.513444ms)","trace[1018103943] 'compare' (duration: 116.310035ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:28:32.874847Z","caller":"traceutil/trace.go:171","msg":"trace[2015866346] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"299.756673ms","start":"2026-04-13T18:28:32.575061Z","end":"2026-04-13T18:28:32.874817Z","steps":["trace[2015866346] 'process raft request' (duration: 299.714442ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:32.875245Z","caller":"traceutil/trace.go:171","msg":"trace[222738648] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"301.545485ms","start":"2026-04-13T18:28:32.573664Z","end":"2026-04-13T18:28:32.875209Z","steps":["trace[222738648] 'process raft request' (duration: 238.258695ms)","trace[222738648] 'compare' (duration: 62.699296ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:28:32.875284Z","caller":"traceutil/trace.go:171","msg":"trace[276438337] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"301.505534ms","start":"2026-04-13T18:28:32.573739Z","end":"2026-04-13T18:28:32.875245Z","steps":["trace[276438337] 'process raft request' (duration: 300.995792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:32.875373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:32.573646Z","time spent":"301.635738ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:28:32.875457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:32.573702Z","time spent":"301.670528ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:28:44.866396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.394286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T18:28:44.866521Z","caller":"traceutil/trace.go:171","msg":"trace[723245999] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1375; }","duration":"111.256825ms","start":"2026-04-13T18:28:44.755252Z","end":"2026-04-13T18:28:44.866509Z","steps":["trace[723245999] 'read index received' (duration: 108.833177ms)","trace[723245999] 'applied index is now lower than readState.Index' (duration: 2.422888ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:28:44.866577Z","caller":"traceutil/trace.go:171","msg":"trace[873010377] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"367.50782ms","start":"2026-04-13T18:28:44.499062Z","end":"2026-04-13T18:28:44.866569Z","steps":["trace[873010377] 'process raft request' (duration: 253.881508ms)","trace[873010377] 'compare' (duration: 113.313684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:44.866616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:44.499037Z","time spent":"367.55764ms","remote":"127.0.0.1:50984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:28:44.866769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.520111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-13T18:28:44.866754Z","caller":"traceutil/trace.go:171","msg":"trace[284134038] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"258.672292ms","start":"2026-04-13T18:28:44.608052Z","end":"2026-04-13T18:28:44.866725Z","steps":["trace[284134038] 'process raft request' (duration: 258.415786ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:44.866803Z","caller":"traceutil/trace.go:171","msg":"trace[1325367280] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1326; }","duration":"111.579823ms","start":"2026-04-13T18:28:44.755213Z","end":"2026-04-13T18:28:44.866793Z","steps":["trace[1325367280] 'agreement among raft nodes before linearized reading' (duration: 111.45391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:44.866986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.35325ms","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-13T18:28:44.867027Z","caller":"traceutil/trace.go:171","msg":"trace[241233944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1326; }","duration":"104.439082ms","start":"2026-04-13T18:28:44.762579Z","end":"2026-04-13T18:28:44.867018Z","steps":["trace[241233944] 'agreement among raft nodes before linearized reading' (duration: 104.295209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.371073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743992399239472,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T18:28:45.827696Z","caller":"traceutil/trace.go:171","msg":"trace[1767155831] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"956.666468ms","start":"2026-04-13T18:28:44.870981Z","end":"2026-04-13T18:28:45.827648Z","steps":["trace[1767155831] 'process raft request' (duration: 951.959715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.827902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:44.870969Z","time spent":"956.860892ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:28:45.827947Z","caller":"traceutil/trace.go:171","msg":"trace[1844904271] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"956.783ms","start":"2026-04-13T18:28:44.871138Z","end":"2026-04-13T18:28:45.827921Z","steps":["trace[1844904271] 'process raft request' (duration: 956.473963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.828071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:44.871124Z","time spent":"956.880222ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T18:28:45.828712Z","caller":"traceutil/trace.go:171","msg":"trace[375237264] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1377; }","duration":"956.721759ms","start":"2026-04-13T18:28:44.871022Z","end":"2026-04-13T18:28:45.827744Z","steps":["trace[375237264] 'read index received' (duration: 951.912194ms)","trace[375237264] 'applied index is now lower than readState.Index' (duration: 4.808435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:28:45.828887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.859696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-13T18:28:45.829559Z","caller":"traceutil/trace.go:171","msg":"trace[2024883994] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1328; }","duration":"958.570853ms","start":"2026-04-13T18:28:44.870978Z","end":"2026-04-13T18:28:45.829549Z","steps":["trace[2024883994] 'agreement among raft nodes before linearized reading' (duration: 957.823425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.829748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:44.870959Z","time spent":"958.778697ms","remote":"127.0.0.1:51196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-13T18:28:45.829028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.493939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-13T18:28:45.829035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.108277ms","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-13T18:28:45.829976Z","caller":"traceutil/trace.go:171","msg":"trace[1615214805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1328; }","duration":"906.099461ms","start":"2026-04-13T18:28:44.923864Z","end":"2026-04-13T18:28:45.829964Z","steps":["trace[1615214805] 'agreement among raft nodes before linearized reading' (duration: 905.039036ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:28:45.829923Z","caller":"traceutil/trace.go:171","msg":"trace[2127586143] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1328; }","duration":"811.404381ms","start":"2026-04-13T18:28:45.018479Z","end":"2026-04-13T18:28:45.829883Z","steps":["trace[2127586143] 'agreement among raft nodes before linearized reading' (duration: 810.442518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.830015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:44.923844Z","time spent":"906.160382ms","remote":"127.0.0.1:51194","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-13T18:28:45.829072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.538172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:28:45.830082Z","caller":"traceutil/trace.go:171","msg":"trace[46799462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"343.569217ms","start":"2026-04-13T18:28:45.486496Z","end":"2026-04-13T18:28:45.830065Z","steps":["trace[46799462] 'agreement among raft nodes before linearized reading' (duration: 342.557883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.82911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.696383ms","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-13T18:28:45.830182Z","caller":"traceutil/trace.go:171","msg":"trace[246132493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1328; }","duration":"904.77346ms","start":"2026-04-13T18:28:44.925389Z","end":"2026-04-13T18:28:45.830162Z","steps":["trace[246132493] 'agreement among raft nodes before linearized reading' (duration: 903.680993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.830197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:45.018455Z","time spent":"811.705238ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-13T18:28:45.830254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:44.925379Z","time spent":"904.858571ms","remote":"127.0.0.1:51194","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-13T18:28:45.830139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:45.486481Z","time spent":"343.643019ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T18:28:45.829102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.497129ms","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-13T18:28:45.83033Z","caller":"traceutil/trace.go:171","msg":"trace[1595369539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1328; }","duration":"904.773539ms","start":"2026-04-13T18:28:44.925546Z","end":"2026-04-13T18:28:45.83032Z","steps":["trace[1595369539] 'agreement among raft nodes before linearized reading' (duration: 903.399107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.830363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:44.92553Z","time spent":"904.82399ms","remote":"127.0.0.1:51194","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-13T18:28:45.829157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.528832ms","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-13T18:28:45.830426Z","caller":"traceutil/trace.go:171","msg":"trace[1901056134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1328; }","duration":"418.831453ms","start":"2026-04-13T18:28:45.41158Z","end":"2026-04-13T18:28:45.830411Z","steps":["trace[1901056134] 'agreement among raft nodes before linearized reading' (duration: 417.511552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:28:45.83047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:28:45.411553Z","time spent":"418.905206ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T18:28:49.77417Z","caller":"traceutil/trace.go:171","msg":"trace[198907495] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"202.386229ms","start":"2026-04-13T18:28:49.57175Z","end":"2026-04-13T18:28:49.774136Z","steps":["trace[198907495] 'process raft request' (duration: 202.160104ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:27.409888Z","caller":"traceutil/trace.go:171","msg":"trace[152010362] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"275.494496ms","start":"2026-04-13T18:29:27.134379Z","end":"2026-04-13T18:29:27.409873Z","steps":["trace[152010362] 'process raft request' (duration: 275.400194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:28.752301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743992399239940,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T18:29:29.252481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743992399239940,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T18:29:29.726387Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.477885332s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T18:29:29.726988Z","caller":"traceutil/trace.go:171","msg":"trace[376852239] linearizableReadLoop","detail":"{readStateIndex:1582; appliedIndex:1581; }","duration":"1.475100536s","start":"2026-04-13T18:29:28.251844Z","end":"2026-04-13T18:29:29.726944Z","steps":["trace[376852239] 'read index received' (duration: 1.474705016s)","trace[376852239] 'applied index is now lower than readState.Index' (duration: 394.42µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:29:29.727076Z","caller":"traceutil/trace.go:171","msg":"trace[2003370354] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"1.478632659s","start":"2026-04-13T18:29:28.24842Z","end":"2026-04-13T18:29:29.727052Z","steps":["trace[2003370354] 'process raft request' (duration: 1.478273831s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.72722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:29:28.248407Z","time spent":"1.478716641s","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:29:29.727279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475415993s","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-13T18:29:29.727361Z","caller":"traceutil/trace.go:171","msg":"trace[1601059584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1523; }","duration":"1.475525516s","start":"2026-04-13T18:29:28.251822Z","end":"2026-04-13T18:29:29.727347Z","steps":["trace[1601059584] 'agreement among raft nodes before linearized reading' (duration: 1.475285601s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.72742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:29:28.251815Z","time spent":"1.475569647s","remote":"127.0.0.1:51194","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-13T18:29:29.728996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.069971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:29:29.729044Z","caller":"traceutil/trace.go:171","msg":"trace[419202787] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1524; }","duration":"268.149744ms","start":"2026-04-13T18:29:29.460881Z","end":"2026-04-13T18:29:29.729031Z","steps":["trace[419202787] 'agreement among raft nodes before linearized reading' (duration: 268.027351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.7291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.437293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-13T18:29:29.729139Z","caller":"traceutil/trace.go:171","msg":"trace[55842628] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1524; }","duration":"896.491145ms","start":"2026-04-13T18:29:28.832631Z","end":"2026-04-13T18:29:29.729122Z","steps":["trace[55842628] 'agreement among raft nodes before linearized reading' (duration: 896.431023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.72917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:29:28.832616Z","time spent":"896.547686ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-13T18:29:29.729342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445269725s","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-13T18:29:29.729382Z","caller":"traceutil/trace.go:171","msg":"trace[845721318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1524; }","duration":"1.445336297s","start":"2026-04-13T18:29:28.284037Z","end":"2026-04-13T18:29:29.729373Z","steps":["trace[845721318] 'agreement among raft nodes before linearized reading' (duration: 1.445250495s)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:29.729391Z","caller":"traceutil/trace.go:171","msg":"trace[1335418245] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"315.657281ms","start":"2026-04-13T18:29:29.413721Z","end":"2026-04-13T18:29:29.729378Z","steps":["trace[1335418245] 'process raft request' (duration: 315.076107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.729416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:29:28.28402Z","time spent":"1.445390298s","remote":"127.0.0.1:51194","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-13T18:29:29.729464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:29:29.413706Z","time spent":"315.714262ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T18:29:29.729473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434878621s","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-13T18:29:29.729547Z","caller":"traceutil/trace.go:171","msg":"trace[523770591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1524; }","duration":"1.434970803s","start":"2026-04-13T18:29:28.294567Z","end":"2026-04-13T18:29:29.729538Z","steps":["trace[523770591] 'agreement among raft nodes before linearized reading' (duration: 1.43483727s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.729592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.199486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:29:29.729638Z","caller":"traceutil/trace.go:171","msg":"trace[1645625267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1524; }","duration":"243.274589ms","start":"2026-04-13T18:29:29.486353Z","end":"2026-04-13T18:29:29.729628Z","steps":["trace[1645625267] 'agreement among raft nodes before linearized reading' (duration: 243.197607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.729032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446121055s","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-13T18:29:29.729768Z","caller":"traceutil/trace.go:171","msg":"trace[1537567952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1524; }","duration":"1.446914524s","start":"2026-04-13T18:29:28.282843Z","end":"2026-04-13T18:29:29.729758Z","steps":["trace[1537567952] 'agreement among raft nodes before linearized reading' (duration: 1.446054904s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.729808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:29:28.282819Z","time spent":"1.446979685s","remote":"127.0.0.1:51194","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-13T18:29:29.72961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:29:28.294558Z","time spent":"1.435044284s","remote":"127.0.0.1:51194","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-13T18:29:29.729382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442428899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-13T18:29:29.729981Z","caller":"traceutil/trace.go:171","msg":"trace[174353712] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1524; }","duration":"1.443057923s","start":"2026-04-13T18:29:28.286914Z","end":"2026-04-13T18:29:29.729972Z","steps":["trace[174353712] 'agreement among raft nodes before linearized reading' (duration: 1.442308085s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:29:29.730019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T18:29:28.286898Z","time spent":"1.443112245s","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-13T18:29:29.92059Z","caller":"traceutil/trace.go:171","msg":"trace[2084785500] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"184.681521ms","start":"2026-04-13T18:29:29.735875Z","end":"2026-04-13T18:29:29.920556Z","steps":["trace[2084785500] 'process raft request' (duration: 178.313432ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:29.920678Z","caller":"traceutil/trace.go:171","msg":"trace[1695227887] transaction","detail":"{read_only:false; response_revision:1528; number_of_response:1; }","duration":"181.686231ms","start":"2026-04-13T18:29:29.738967Z","end":"2026-04-13T18:29:29.920654Z","steps":["trace[1695227887] 'process raft request' (duration: 181.564418ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:29.920704Z","caller":"traceutil/trace.go:171","msg":"trace[677652848] transaction","detail":"{read_only:false; response_revision:1527; number_of_response:1; }","duration":"182.847998ms","start":"2026-04-13T18:29:29.737787Z","end":"2026-04-13T18:29:29.920635Z","steps":["trace[677652848] 'process raft request' (duration: 182.700895ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:29.920917Z","caller":"traceutil/trace.go:171","msg":"trace[1878690372] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"180.462742ms","start":"2026-04-13T18:29:29.740439Z","end":"2026-04-13T18:29:29.920902Z","steps":["trace[1878690372] 'process raft request' (duration: 180.123054ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:29.921032Z","caller":"traceutil/trace.go:171","msg":"trace[589480712] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"180.39202ms","start":"2026-04-13T18:29:29.740626Z","end":"2026-04-13T18:29:29.921018Z","steps":["trace[589480712] 'process raft request' (duration: 179.9727ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:29.960036Z","caller":"traceutil/trace.go:171","msg":"trace[594717088] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"212.542137ms","start":"2026-04-13T18:29:29.747466Z","end":"2026-04-13T18:29:29.960008Z","steps":["trace[594717088] 'process raft request' (duration: 212.429924ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:31.013109Z","caller":"traceutil/trace.go:171","msg":"trace[552457076] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"103.066963ms","start":"2026-04-13T18:29:30.910019Z","end":"2026-04-13T18:29:31.013086Z","steps":["trace[552457076] 'process raft request' (duration: 102.894679ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:31.501953Z","caller":"traceutil/trace.go:171","msg":"trace[1105251318] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"128.90841ms","start":"2026-04-13T18:29:31.373021Z","end":"2026-04-13T18:29:31.501929Z","steps":["trace[1105251318] 'process raft request' (duration: 128.705945ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:29:48.302172Z","caller":"traceutil/trace.go:171","msg":"trace[1942503567] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"113.904848ms","start":"2026-04-13T18:29:48.188244Z","end":"2026-04-13T18:29:48.302148Z","steps":["trace[1942503567] 'process raft request' (duration: 26.432442ms)","trace[1942503567] 'compare' (duration: 87.385404ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:29:48.335974Z","caller":"traceutil/trace.go:171","msg":"trace[2145556318] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"147.292313ms","start":"2026-04-13T18:29:48.18866Z","end":"2026-04-13T18:29:48.335952Z","steps":["trace[2145556318] 'process raft request' (duration: 147.1496ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:30:01.2324Z","caller":"traceutil/trace.go:171","msg":"trace[2017385347] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"136.642792ms","start":"2026-04-13T18:30:01.095732Z","end":"2026-04-13T18:30:01.232375Z","steps":["trace[2017385347] 'process raft request' (duration: 136.503649ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:30:14.449874Z","caller":"traceutil/trace.go:171","msg":"trace[147507617] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"105.009209ms","start":"2026-04-13T18:30:14.344838Z","end":"2026-04-13T18:30:14.449847Z","steps":["trace[147507617] 'process raft request' (duration: 104.851646ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:30:48.893633Z","caller":"traceutil/trace.go:171","msg":"trace[2117375770] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1959; }","duration":"127.763595ms","start":"2026-04-13T18:30:48.765846Z","end":"2026-04-13T18:30:48.89361Z","steps":["trace[2117375770] 'read index received' (duration: 6.184548ms)","trace[2117375770] 'applied index is now lower than readState.Index' (duration: 121.577967ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:30:48.89375Z","caller":"traceutil/trace.go:171","msg":"trace[1145186033] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"132.190512ms","start":"2026-04-13T18:30:48.761529Z","end":"2026-04-13T18:30:48.89372Z","steps":["trace[1145186033] 'process raft request' (duration: 131.919085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:30:48.893886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.023231ms","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-13T18:30:48.89396Z","caller":"traceutil/trace.go:171","msg":"trace[71354047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"128.130454ms","start":"2026-04-13T18:30:48.765814Z","end":"2026-04-13T18:30:48.893945Z","steps":["trace[71354047] 'agreement among raft nodes before linearized reading' (duration: 127.923669ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:30:48.923072Z","caller":"traceutil/trace.go:171","msg":"trace[1399982879] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"143.232276ms","start":"2026-04-13T18:30:48.779814Z","end":"2026-04-13T18:30:48.923047Z","steps":["trace[1399982879] 'process raft request' (duration: 143.051352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:30:48.923162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.12889ms","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-13T18:30:48.923216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.558431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-13T18:30:48.923249Z","caller":"traceutil/trace.go:171","msg":"trace[1333400284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1887; }","duration":"147.269584ms","start":"2026-04-13T18:30:48.775962Z","end":"2026-04-13T18:30:48.923232Z","steps":["trace[1333400284] 'agreement among raft nodes before linearized reading' (duration: 147.049868ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:30:48.923277Z","caller":"traceutil/trace.go:171","msg":"trace[1367229983] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1887; }","duration":"112.662443ms","start":"2026-04-13T18:30:48.810599Z","end":"2026-04-13T18:30:48.923262Z","steps":["trace[1367229983] 'agreement among raft nodes before linearized reading' (duration: 112.472249ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:31:07.401775Z","caller":"traceutil/trace.go:171","msg":"trace[924755723] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"160.538052ms","start":"2026-04-13T18:31:07.241214Z","end":"2026-04-13T18:31:07.401752Z","steps":["trace[924755723] 'read index received' (duration: 160.358688ms)","trace[924755723] 'applied index is now lower than readState.Index' (duration: 178.484µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:31:07.401839Z","caller":"traceutil/trace.go:171","msg":"trace[1921626296] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"181.348611ms","start":"2026-04-13T18:31:07.220463Z","end":"2026-04-13T18:31:07.401812Z","steps":["trace[1921626296] 'process raft request' (duration: 181.156157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:31:07.402012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.783477ms","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-13T18:31:07.402072Z","caller":"traceutil/trace.go:171","msg":"trace[119626048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1971; }","duration":"160.88368ms","start":"2026-04-13T18:31:07.241175Z","end":"2026-04-13T18:31:07.402058Z","steps":["trace[119626048] 'agreement among raft nodes before linearized reading' (duration: 160.703436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T18:31:07.402185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.864577ms","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-13T18:31:07.402295Z","caller":"traceutil/trace.go:171","msg":"trace[568646901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1971; }","duration":"148.013581ms","start":"2026-04-13T18:31:07.254259Z","end":"2026-04-13T18:31:07.402272Z","steps":["trace[568646901] 'agreement among raft nodes before linearized reading' (duration: 147.713234ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:31:11.584125Z","caller":"traceutil/trace.go:171","msg":"trace[1949915615] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"108.631366ms","start":"2026-04-13T18:31:11.475452Z","end":"2026-04-13T18:31:11.584084Z","steps":["trace[1949915615] 'read index received' (duration: 59.015976ms)","trace[1949915615] 'applied index is now lower than readState.Index' (duration: 49.61454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:31:11.584433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.968874ms","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-13T18:31:11.584502Z","caller":"traceutil/trace.go:171","msg":"trace[528957011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1990; }","duration":"109.075627ms","start":"2026-04-13T18:31:11.475411Z","end":"2026-04-13T18:31:11.584486Z","steps":["trace[528957011] 'agreement among raft nodes before linearized reading' (duration: 108.80773ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T18:31:11.584697Z","caller":"traceutil/trace.go:171","msg":"trace[526029516] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"131.398462ms","start":"2026-04-13T18:31:11.452833Z","end":"2026-04-13T18:31:11.584232Z","steps":["trace[526029516] 'process raft request' (duration: 81.654348ms)","trace[526029516] 'compare' (duration: 49.416176ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:31:51.495888Z","caller":"traceutil/trace.go:171","msg":"trace[1494585692] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2264; }","duration":"101.032661ms","start":"2026-04-13T18:31:51.394811Z","end":"2026-04-13T18:31:51.495844Z","steps":["trace[1494585692] 'read index received' (duration: 37.470517ms)","trace[1494585692] 'applied index is now lower than readState.Index' (duration: 63.560004ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T18:31:51.495954Z","caller":"traceutil/trace.go:171","msg":"trace[1998711131] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"103.883068ms","start":"2026-04-13T18:31:51.392041Z","end":"2026-04-13T18:31:51.495924Z","steps":["trace[1998711131] 'process raft request' (duration: 40.198362ms)","trace[1998711131] 'compare' (duration: 63.295017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T18:31:51.496078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.249886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T18:31:51.49636Z","caller":"traceutil/trace.go:171","msg":"trace[1998933589] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:2176; }","duration":"101.501942ms","start":"2026-04-13T18:31:51.394766Z","end":"2026-04-13T18:31:51.496268Z","steps":["trace[1998933589] 'agreement among raft nodes before linearized reading' (duration: 101.246736ms)"],"step_count":1}