{"level":"warn","ts":"2026-04-27T19:46:52.879308Z","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-27T19:46:52.879408Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.39: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.19.213.39:2380","--initial-cluster=instance=https://199.19.213.39:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.39:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.39: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-27T19:46:52.879476Z","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-27T19:46:52.879487Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.39:2380"]} {"level":"info","ts":"2026-04-27T19:46:52.879503Z","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-27T19:46:52.880146Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"]} {"level":"info","ts":"2026-04-27T19:46:52.880214Z","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.19.213.39:2380"],"listen-peer-urls":["https://199.19.213.39:2380"],"advertise-client-urls":["https://199.19.213.39:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.39: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-27T19:46:52.885238Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.818852ms"} {"level":"info","ts":"2026-04-27T19:46:52.893066Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95026ee9c61f87d3","cluster-id":"98e25849a4b355e0"} {"level":"info","ts":"2026-04-27T19:46:52.893358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T19:46:52.893509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became follower at term 0"} {"level":"info","ts":"2026-04-27T19:46:52.893575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95026ee9c61f87d3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T19:46:52.893683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became follower at term 1"} {"level":"info","ts":"2026-04-27T19:46:52.894031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=(10737266411935074259)"} {"level":"warn","ts":"2026-04-27T19:46:52.900003Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T19:46:52.901392Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T19:46:52.902661Z","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-27T19:46:52.905322Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95026ee9c61f87d3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T19:46:52.905675Z","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-27T19:46:52.905755Z","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-27T19:46:52.905767Z","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-27T19:46:52.905878Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95026ee9c61f87d3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T19:46:52.906875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=(10737266411935074259)"} {"level":"info","ts":"2026-04-27T19:46:52.907091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98e25849a4b355e0","local-member-id":"95026ee9c61f87d3","added-peer-id":"95026ee9c61f87d3","added-peer-peer-urls":["https://199.19.213.39:2380"]} {"level":"info","ts":"2026-04-27T19:46:52.909565Z","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-27T19:46:52.909765Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.39:2380"} {"level":"info","ts":"2026-04-27T19:46:52.909797Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.39:2380"} {"level":"info","ts":"2026-04-27T19:46:52.910029Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95026ee9c61f87d3","initial-advertise-peer-urls":["https://199.19.213.39:2380"],"listen-peer-urls":["https://199.19.213.39:2380"],"advertise-client-urls":["https://199.19.213.39:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T19:46:52.910065Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T19:46:53.895462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T19:46:53.89552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T19:46:53.895553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 received MsgPreVoteResp from 95026ee9c61f87d3 at term 1"} {"level":"info","ts":"2026-04-27T19:46:53.895723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became candidate at term 2"} {"level":"info","ts":"2026-04-27T19:46:53.895748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 received MsgVoteResp from 95026ee9c61f87d3 at term 2"} {"level":"info","ts":"2026-04-27T19:46:53.895765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became leader at term 2"} {"level":"info","ts":"2026-04-27T19:46:53.895782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95026ee9c61f87d3 elected leader 95026ee9c61f87d3 at term 2"} {"level":"info","ts":"2026-04-27T19:46:53.896708Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:46:53.897728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T19:46:53.897733Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95026ee9c61f87d3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.39:2379]}","request-path":"/0/members/95026ee9c61f87d3/attributes","cluster-id":"98e25849a4b355e0","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T19:46:53.89777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T19:46:53.898079Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T19:46:53.898128Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T19:46:53.898307Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98e25849a4b355e0","local-member-id":"95026ee9c61f87d3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:46:53.898473Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:46:53.898527Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:46:53.902911Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.39:2379"} {"level":"info","ts":"2026-04-27T19:46:53.90351Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T19:47:16.124633Z","caller":"traceutil/trace.go:171","msg":"trace[181269690] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"110.908355ms","start":"2026-04-27T19:47:16.013687Z","end":"2026-04-27T19:47:16.124595Z","steps":["trace[181269690] 'process raft request' (duration: 110.598137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:20.04993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948011011,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:47:20.393077Z","caller":"traceutil/trace.go:171","msg":"trace[123974346] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"844.262191ms","start":"2026-04-27T19:47:19.548799Z","end":"2026-04-27T19:47:20.393061Z","steps":["trace[123974346] 'read index received' (duration: 844.142298ms)","trace[123974346] 'applied index is now lower than readState.Index' (duration: 119.393µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:20.393385Z","caller":"traceutil/trace.go:171","msg":"trace[1002964328] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"887.783479ms","start":"2026-04-27T19:47:19.505594Z","end":"2026-04-27T19:47:20.393378Z","steps":["trace[1002964328] 'process raft request' (duration: 887.389009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:20.393573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.711304ms","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-27T19:47:20.393649Z","caller":"traceutil/trace.go:171","msg":"trace[803217811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"844.883668ms","start":"2026-04-27T19:47:19.54875Z","end":"2026-04-27T19:47:20.393634Z","steps":["trace[803217811] 'agreement among raft nodes before linearized reading' (duration: 844.685233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:20.393678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:19.548726Z","time spent":"844.946219ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:47:20.393596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.860004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:47:20.393885Z","caller":"traceutil/trace.go:171","msg":"trace[1518377830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"572.184532ms","start":"2026-04-27T19:47:19.821665Z","end":"2026-04-27T19:47:20.393849Z","steps":["trace[1518377830] 'agreement among raft nodes before linearized reading' (duration: 571.720581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:20.39402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:19.821649Z","time spent":"572.353606ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:47:20.39379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:19.505578Z","time spent":"887.827981ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:20.561381Z","caller":"traceutil/trace.go:171","msg":"trace[1446094866] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"164.016567ms","start":"2026-04-27T19:47:20.397341Z","end":"2026-04-27T19:47:20.561358Z","steps":["trace[1446094866] 'process raft request' (duration: 153.050068ms)","trace[1446094866] 'compare' (duration: 10.18496ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:21.26535Z","caller":"traceutil/trace.go:171","msg":"trace[518009328] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"161.737739ms","start":"2026-04-27T19:47:21.103593Z","end":"2026-04-27T19:47:21.265331Z","steps":["trace[518009328] 'process raft request' (duration: 161.608636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:22.973521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150783517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:47:22.973584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.893627ms","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-27T19:47:22.973674Z","caller":"traceutil/trace.go:171","msg":"trace[1035173738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"1.150962482s","start":"2026-04-27T19:47:21.822689Z","end":"2026-04-27T19:47:22.973652Z","steps":["trace[1035173738] 'range keys from in-memory index tree' (duration: 1.150708886s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:22.973716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:21.822668Z","time spent":"1.151038614s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:47:22.973726Z","caller":"traceutil/trace.go:171","msg":"trace[1449367444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"576.094452ms","start":"2026-04-27T19:47:22.397577Z","end":"2026-04-27T19:47:22.973671Z","steps":["trace[1449367444] 'range keys from in-memory index tree' (duration: 575.772094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:22.973804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:22.397562Z","time spent":"576.226395ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:47:22.973159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.959536ms","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-27T19:47:22.975358Z","caller":"traceutil/trace.go:171","msg":"trace[1316573358] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:477; }","duration":"305.160642ms","start":"2026-04-27T19:47:22.670161Z","end":"2026-04-27T19:47:22.975322Z","steps":["trace[1316573358] 'range keys from in-memory index tree' (duration: 302.941865ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:22.981586Z","caller":"traceutil/trace.go:171","msg":"trace[1617962865] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"416.654742ms","start":"2026-04-27T19:47:22.564917Z","end":"2026-04-27T19:47:22.981572Z","steps":["trace[1617962865] 'read index received' (duration: 410.738621ms)","trace[1617962865] 'applied index is now lower than readState.Index' (duration: 5.915021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:22.981829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.883708ms","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-27T19:47:22.981878Z","caller":"traceutil/trace.go:171","msg":"trace[1430310651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"417.00356ms","start":"2026-04-27T19:47:22.564863Z","end":"2026-04-27T19:47:22.981867Z","steps":["trace[1430310651] 'agreement among raft nodes before linearized reading' (duration: 416.757634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:22.98197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:22.564837Z","time spent":"417.066872ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:47:22.982675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.26398ms","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-27T19:47:22.982773Z","caller":"traceutil/trace.go:171","msg":"trace[1120914448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"414.406974ms","start":"2026-04-27T19:47:22.568349Z","end":"2026-04-27T19:47:22.982756Z","steps":["trace[1120914448] 'agreement among raft nodes before linearized reading' (duration: 413.894251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:22.98402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:22.568326Z","time spent":"415.677037ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:47:24.595336Z","caller":"traceutil/trace.go:171","msg":"trace[1924475714] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"156.66728ms","start":"2026-04-27T19:47:24.438633Z","end":"2026-04-27T19:47:24.5953Z","steps":["trace[1924475714] 'process raft request' (duration: 156.304391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:26.78475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.303551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:47:26.78484Z","caller":"traceutil/trace.go:171","msg":"trace[53140083] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"255.001845ms","start":"2026-04-27T19:47:26.529825Z","end":"2026-04-27T19:47:26.784827Z","steps":["trace[53140083] 'process raft request' (duration: 151.465878ms)","trace[53140083] 'compare' (duration: 103.095186ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:26.789043Z","caller":"traceutil/trace.go:171","msg":"trace[1148220610] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"243.122792ms","start":"2026-04-27T19:47:26.545906Z","end":"2026-04-27T19:47:26.789029Z","steps":["trace[1148220610] 'process raft request' (duration: 243.002019ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:26.789223Z","caller":"traceutil/trace.go:171","msg":"trace[194559332] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"258.837991ms","start":"2026-04-27T19:47:26.530362Z","end":"2026-04-27T19:47:26.7892Z","steps":["trace[194559332] 'process raft request' (duration: 258.39584ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:27.012967Z","caller":"traceutil/trace.go:171","msg":"trace[572417096] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"212.244486ms","start":"2026-04-27T19:47:26.800699Z","end":"2026-04-27T19:47:27.012944Z","steps":["trace[572417096] 'process raft request' (duration: 204.288333ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:27.013987Z","caller":"traceutil/trace.go:171","msg":"trace[375871557] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"193.546889ms","start":"2026-04-27T19:47:26.820426Z","end":"2026-04-27T19:47:27.013973Z","steps":["trace[375871557] 'process raft request' (duration: 193.071757ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:27.01463Z","caller":"traceutil/trace.go:171","msg":"trace[2051160744] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"166.88316ms","start":"2026-04-27T19:47:26.847729Z","end":"2026-04-27T19:47:27.014612Z","steps":["trace[2051160744] 'process raft request' (duration: 166.213323ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:27.01477Z","caller":"traceutil/trace.go:171","msg":"trace[41133659] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"130.608146ms","start":"2026-04-27T19:47:26.884153Z","end":"2026-04-27T19:47:27.014761Z","steps":["trace[41133659] 'process raft request' (duration: 130.140253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:27.788796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948011157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:47:28.317879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.746922ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-27T19:47:28.318239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.026036Z","time spent":"1.292195829s","remote":"127.0.0.1:42536","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T19:47:28.318472Z","caller":"traceutil/trace.go:171","msg":"trace[30656784] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:582; }","duration":"1.030359471s","start":"2026-04-27T19:47:27.288095Z","end":"2026-04-27T19:47:28.318455Z","steps":["trace[30656784] 'read index received' (duration: 703.084426ms)","trace[30656784] 'applied index is now lower than readState.Index' (duration: 327.274265ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:28.318643Z","caller":"traceutil/trace.go:171","msg":"trace[1491530331] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.223065169s","start":"2026-04-27T19:47:27.095557Z","end":"2026-04-27T19:47:28.318622Z","steps":["trace[1491530331] 'process raft request' (duration: 1.222848374s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:28.318745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.095528Z","time spent":"1.223156791s","remote":"127.0.0.1:42554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:47:28.318857Z","caller":"traceutil/trace.go:171","msg":"trace[1075423793] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.289225293s","start":"2026-04-27T19:47:27.02962Z","end":"2026-04-27T19:47:28.318845Z","steps":["trace[1075423793] 'process raft request' (duration: 1.288668379s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:28.318915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.029611Z","time spent":"1.289267284s","remote":"127.0.0.1:42554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:28.319053Z","caller":"traceutil/trace.go:171","msg":"trace[828576016] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.292703272s","start":"2026-04-27T19:47:27.026267Z","end":"2026-04-27T19:47:28.31897Z","steps":["trace[828576016] 'process raft request' (duration: 1.291762028s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:28.319152Z","caller":"traceutil/trace.go:171","msg":"trace[1032748641] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.288763751s","start":"2026-04-27T19:47:27.030375Z","end":"2026-04-27T19:47:28.319139Z","steps":["trace[1032748641] 'process raft request' (duration: 1.287970581s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:28.319171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.800512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:47:28.319217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.030369Z","time spent":"1.288812833s","remote":"127.0.0.1:42554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:28.319225Z","caller":"traceutil/trace.go:171","msg":"trace[349295411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"496.903785ms","start":"2026-04-27T19:47:27.822302Z","end":"2026-04-27T19:47:28.319206Z","steps":["trace[349295411] 'agreement among raft nodes before linearized reading' (duration: 496.817192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:28.319262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.822271Z","time spent":"496.984217ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:47:28.319277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.026263Z","time spent":"1.292892976s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:28.319334Z","caller":"traceutil/trace.go:171","msg":"trace[839124755] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.292737703s","start":"2026-04-27T19:47:27.026587Z","end":"2026-04-27T19:47:28.319325Z","steps":["trace[839124755] 'process raft request' (duration: 1.291629575s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:28.319375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.026581Z","time spent":"1.292774793s","remote":"127.0.0.1:43030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:47:28.31951Z","caller":"traceutil/trace.go:171","msg":"trace[2138698757] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.293281236s","start":"2026-04-27T19:47:27.026218Z","end":"2026-04-27T19:47:28.319499Z","steps":["trace[2138698757] 'process raft request' (duration: 1.291937582s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:28.319562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.026199Z","time spent":"1.293332898s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:28.319626Z","caller":"traceutil/trace.go:171","msg":"trace[75940087] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.291131851s","start":"2026-04-27T19:47:27.02847Z","end":"2026-04-27T19:47:28.319602Z","steps":["trace[75940087] 'process raft request' (duration: 1.289779458s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:28.319788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.028445Z","time spent":"1.291247115s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:47:28.31988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03074597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:47:28.319926Z","caller":"traceutil/trace.go:171","msg":"trace[954895364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"1.031844528s","start":"2026-04-27T19:47:27.288067Z","end":"2026-04-27T19:47:28.319912Z","steps":["trace[954895364] 'agreement among raft nodes before linearized reading' (duration: 1.030654698s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:28.319961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:27.288053Z","time spent":"1.031896499s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T19:47:28.747099Z","caller":"traceutil/trace.go:171","msg":"trace[384685081] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"181.670597ms","start":"2026-04-27T19:47:28.565399Z","end":"2026-04-27T19:47:28.747069Z","steps":["trace[384685081] 'process raft request' (duration: 181.322258ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:28.838069Z","caller":"traceutil/trace.go:171","msg":"trace[585140291] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"238.968786ms","start":"2026-04-27T19:47:28.599078Z","end":"2026-04-27T19:47:28.838046Z","steps":["trace[585140291] 'process raft request' (duration: 238.763331ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:28.950106Z","caller":"traceutil/trace.go:171","msg":"trace[1899936815] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"175.50947ms","start":"2026-04-27T19:47:28.774543Z","end":"2026-04-27T19:47:28.950053Z","steps":["trace[1899936815] 'process raft request' (duration: 175.408967ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:28.95017Z","caller":"traceutil/trace.go:171","msg":"trace[1365647281] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"179.518702ms","start":"2026-04-27T19:47:28.770582Z","end":"2026-04-27T19:47:28.950101Z","steps":["trace[1365647281] 'process raft request' (duration: 139.37936ms)","trace[1365647281] 'compare' (duration: 39.422854ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:29.859249Z","caller":"traceutil/trace.go:171","msg":"trace[1267097675] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"385.457057ms","start":"2026-04-27T19:47:29.473769Z","end":"2026-04-27T19:47:29.859226Z","steps":["trace[1267097675] 'read index received' (duration: 384.7528ms)","trace[1267097675] 'applied index is now lower than readState.Index' (duration: 703.287µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:29.859321Z","caller":"traceutil/trace.go:171","msg":"trace[1946399746] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"392.554518ms","start":"2026-04-27T19:47:29.466731Z","end":"2026-04-27T19:47:29.859286Z","steps":["trace[1946399746] 'process raft request' (duration: 391.990774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:29.85947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.677693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"warn","ts":"2026-04-27T19:47:29.859486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:29.466717Z","time spent":"392.679611ms","remote":"127.0.0.1:42554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:29.859514Z","caller":"traceutil/trace.go:171","msg":"trace[158574909] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:599; }","duration":"385.774415ms","start":"2026-04-27T19:47:29.473727Z","end":"2026-04-27T19:47:29.859501Z","steps":["trace[158574909] 'agreement among raft nodes before linearized reading' (duration: 385.625362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:29.859596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:29.473699Z","time spent":"385.887578ms","remote":"127.0.0.1:42554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9173,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-04-27T19:47:35.273204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.326938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:47:35.273449Z","caller":"traceutil/trace.go:171","msg":"trace[353852632] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"675.411623ms","start":"2026-04-27T19:47:34.59801Z","end":"2026-04-27T19:47:35.273422Z","steps":["trace[353852632] 'process raft request' (duration: 555.449967ms)","trace[353852632] 'compare' (duration: 118.504268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:35.273521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:34.597976Z","time spent":"675.519455ms","remote":"127.0.0.1:42588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:47:35.273625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.687756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T19:47:35.273714Z","caller":"traceutil/trace.go:171","msg":"trace[549609347] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:629; }","duration":"596.82809ms","start":"2026-04-27T19:47:34.676866Z","end":"2026-04-27T19:47:35.273694Z","steps":["trace[549609347] 'agreement among raft nodes before linearized reading' (duration: 596.621684ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:35.273722Z","caller":"traceutil/trace.go:171","msg":"trace[804962651] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"596.42231ms","start":"2026-04-27T19:47:34.676921Z","end":"2026-04-27T19:47:35.273343Z","steps":["trace[804962651] 'read index received' (duration: 476.473194ms)","trace[804962651] 'applied index is now lower than readState.Index' (duration: 119.947106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:35.273756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:34.676842Z","time spent":"596.906922ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T19:47:35.278006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.962267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:47:35.278071Z","caller":"traceutil/trace.go:171","msg":"trace[1000921152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"457.093521ms","start":"2026-04-27T19:47:34.820962Z","end":"2026-04-27T19:47:35.278055Z","steps":["trace[1000921152] 'agreement among raft nodes before linearized reading' (duration: 456.956958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:35.278114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:34.820931Z","time spent":"457.172522ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:47:35.279499Z","caller":"traceutil/trace.go:171","msg":"trace[1808125367] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"542.668951ms","start":"2026-04-27T19:47:34.736815Z","end":"2026-04-27T19:47:35.279484Z","steps":["trace[1808125367] 'process raft request' (duration: 540.214618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:35.279621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:34.736782Z","time spent":"542.765023ms","remote":"127.0.0.1:42608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:35.524076Z","caller":"traceutil/trace.go:171","msg":"trace[1251372751] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"152.822583ms","start":"2026-04-27T19:47:35.371221Z","end":"2026-04-27T19:47:35.524043Z","steps":["trace[1251372751] 'process raft request' (duration: 104.676127ms)","trace[1251372751] 'compare' (duration: 48.008622ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:36.73062Z","caller":"traceutil/trace.go:171","msg":"trace[350319328] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"766.099051ms","start":"2026-04-27T19:47:35.964502Z","end":"2026-04-27T19:47:36.730601Z","steps":["trace[350319328] 'process raft request' (duration: 764.527751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:36.731048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:35.964477Z","time spent":"766.199044ms","remote":"127.0.0.1:42608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:36.739344Z","caller":"traceutil/trace.go:171","msg":"trace[601228800] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"455.43087ms","start":"2026-04-27T19:47:36.283866Z","end":"2026-04-27T19:47:36.739297Z","steps":["trace[601228800] 'read index received' (duration: 444.46018ms)","trace[601228800] 'applied index is now lower than readState.Index' (duration: 10.96945ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:36.739408Z","caller":"traceutil/trace.go:171","msg":"trace[207649983] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"465.105785ms","start":"2026-04-27T19:47:36.274283Z","end":"2026-04-27T19:47:36.739388Z","steps":["trace[207649983] 'process raft request' (duration: 464.829978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:36.739513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.645515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-swhvc\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-27T19:47:36.739551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.261696ms","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-27T19:47:36.739556Z","caller":"traceutil/trace.go:171","msg":"trace[1842944012] range","detail":"{range_begin:/registry/pods/kube-system/cilium-swhvc; range_end:; response_count:1; response_revision:634; }","duration":"455.714167ms","start":"2026-04-27T19:47:36.28383Z","end":"2026-04-27T19:47:36.739545Z","steps":["trace[1842944012] 'agreement among raft nodes before linearized reading' (duration: 455.598674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:36.739547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.274206Z","time spent":"465.240368ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:47:36.739588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.283815Z","time spent":"455.767578ms","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-swhvc\" "} {"level":"warn","ts":"2026-04-27T19:47:36.739651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.633992ms","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-27T19:47:36.739588Z","caller":"traceutil/trace.go:171","msg":"trace[377063801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"373.348299ms","start":"2026-04-27T19:47:36.366228Z","end":"2026-04-27T19:47:36.739576Z","steps":["trace[377063801] 'agreement among raft nodes before linearized reading' (duration: 373.228776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:36.739706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.366199Z","time spent":"373.500192ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:47:36.739711Z","caller":"traceutil/trace.go:171","msg":"trace[1531623860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"365.672613ms","start":"2026-04-27T19:47:36.374007Z","end":"2026-04-27T19:47:36.73968Z","steps":["trace[1531623860] 'agreement among raft nodes before linearized reading' (duration: 365.477368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:36.739722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.229137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T19:47:36.739737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.37398Z","time spent":"365.750775ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T19:47:36.739749Z","caller":"traceutil/trace.go:171","msg":"trace[1310714939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"366.271899ms","start":"2026-04-27T19:47:36.373468Z","end":"2026-04-27T19:47:36.73974Z","steps":["trace[1310714939] 'agreement among raft nodes before linearized reading' (duration: 366.204197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:36.739787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.373459Z","time spent":"366.32056ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:47:36.739657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.93592ms","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-27T19:47:36.73986Z","caller":"traceutil/trace.go:171","msg":"trace[192831688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"366.164216ms","start":"2026-04-27T19:47:36.373689Z","end":"2026-04-27T19:47:36.739853Z","steps":["trace[192831688] 'agreement among raft nodes before linearized reading' (duration: 365.915019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:36.739883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.373674Z","time spent":"366.203337ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:47:37.323672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948011365,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:47:37.824244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948011365,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:47:38.325347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948011365,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:47:38.449371Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.688662727s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T19:47:38.82291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000216762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T19:47:38.82302Z","caller":"traceutil/trace.go:171","msg":"trace[1922321014] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000388315s","start":"2026-04-27T19:47:36.822614Z","end":"2026-04-27T19:47:38.823002Z","steps":["trace[1922321014] 'agreement among raft nodes before linearized reading' (duration: 2.000241432s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:38.823076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.822597Z","time spent":"2.000464608s","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:47:39.391782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.065333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:47:39.391863Z","caller":"traceutil/trace.go:171","msg":"trace[358350687] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.645826774s","start":"2026-04-27T19:47:36.746022Z","end":"2026-04-27T19:47:39.391849Z","steps":["trace[358350687] 'process raft request' (duration: 1.703634378s)","trace[358350687] 'compare' (duration: 941.608541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:39.391905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.745999Z","time spent":"2.645887025s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:47:40.535072Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.203915621s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:47:40.53586Z","caller":"traceutil/trace.go:171","msg":"trace[1733321176] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"3.784177974s","start":"2026-04-27T19:47:36.751659Z","end":"2026-04-27T19:47:40.535837Z","steps":["trace[1733321176] 'process raft request' (duration: 3.783950828s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:40.535869Z","caller":"traceutil/trace.go:171","msg":"trace[798668888] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"3.784167534s","start":"2026-04-27T19:47:36.75167Z","end":"2026-04-27T19:47:40.535838Z","steps":["trace[798668888] 'process raft request' (duration: 3.784078062s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:40.535955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.751645Z","time spent":"3.784251725s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:47:40.536017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.751649Z","time spent":"3.784288626s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:40.536134Z","caller":"traceutil/trace.go:171","msg":"trace[606495962] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"3.78403773s","start":"2026-04-27T19:47:36.752083Z","end":"2026-04-27T19:47:40.536121Z","steps":["trace[606495962] 'process raft request' (duration: 3.783713802s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:40.536191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.752073Z","time spent":"3.784090052s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:40.536362Z","caller":"traceutil/trace.go:171","msg":"trace[934052835] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"3.773942823s","start":"2026-04-27T19:47:36.762411Z","end":"2026-04-27T19:47:40.536354Z","steps":["trace[934052835] 'process raft request' (duration: 3.773562863s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:40.536433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.762401Z","time spent":"3.773985175s","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:40.536603Z","caller":"traceutil/trace.go:171","msg":"trace[87933851] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:656; }","duration":"3.713933344s","start":"2026-04-27T19:47:36.82266Z","end":"2026-04-27T19:47:40.536593Z","steps":["trace[87933851] 'read index received' (duration: 1.626982705s)","trace[87933851] 'applied index is now lower than readState.Index' (duration: 2.086949779s)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:40.536632Z","caller":"traceutil/trace.go:171","msg":"trace[84492702] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"3.726036123s","start":"2026-04-27T19:47:36.810577Z","end":"2026-04-27T19:47:40.536613Z","steps":["trace[84492702] 'process raft request' (duration: 3.725639573s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:40.53671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.810562Z","time spent":"3.726116305s","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:47:40.539059Z","caller":"traceutil/trace.go:171","msg":"trace[1017313426] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.712612964s","start":"2026-04-27T19:47:38.826432Z","end":"2026-04-27T19:47:40.539045Z","steps":["trace[1017313426] 'process raft request' (duration: 1.712132012s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:40.539209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:38.826406Z","time spent":"1.712696867s","remote":"127.0.0.1:42588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:47:40.539598Z","caller":"traceutil/trace.go:171","msg":"trace[441177753] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.193545336s","start":"2026-04-27T19:47:39.346044Z","end":"2026-04-27T19:47:40.53959Z","steps":["trace[441177753] 'process raft request' (duration: 1.192570572s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:40.539723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:39.346006Z","time spent":"1.193618878s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:47:40.539958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70924526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:47:40.53999Z","caller":"traceutil/trace.go:171","msg":"trace[1563919684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.709324142s","start":"2026-04-27T19:47:38.830658Z","end":"2026-04-27T19:47:40.539982Z","steps":["trace[1563919684] 'agreement among raft nodes before linearized reading' (duration: 1.709277861s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:40.540114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:38.830633Z","time spent":"1.709434695s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:47:40.54032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.572876612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.39\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T19:47:40.540352Z","caller":"traceutil/trace.go:171","msg":"trace[1296844788] range","detail":"{range_begin:/registry/masterleases/199.19.213.39; range_end:; response_count:1; response_revision:642; }","duration":"3.574490654s","start":"2026-04-27T19:47:36.965852Z","end":"2026-04-27T19:47:40.540343Z","steps":["trace[1296844788] 'agreement among raft nodes before linearized reading' (duration: 3.57279676s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:40.540465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:36.965835Z","time spent":"3.574622117s","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.39\" "} {"level":"warn","ts":"2026-04-27T19:47:42.866862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.215512ms","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-27T19:47:42.86698Z","caller":"traceutil/trace.go:171","msg":"trace[1628461002] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:680; }","duration":"197.348186ms","start":"2026-04-27T19:47:42.669611Z","end":"2026-04-27T19:47:42.866959Z","steps":["trace[1628461002] 'range keys from in-memory index tree' (duration: 197.201782ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:42.86705Z","caller":"traceutil/trace.go:171","msg":"trace[137812990] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"321.462607ms","start":"2026-04-27T19:47:42.545558Z","end":"2026-04-27T19:47:42.867021Z","steps":["trace[137812990] 'process raft request' (duration: 302.648599ms)","trace[137812990] 'compare' (duration: 18.592983ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:42.867352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:42.545546Z","time spent":"321.550619ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:42.867815Z","caller":"traceutil/trace.go:171","msg":"trace[729471473] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"321.422956ms","start":"2026-04-27T19:47:42.546363Z","end":"2026-04-27T19:47:42.867786Z","steps":["trace[729471473] 'process raft request' (duration: 321.267962ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:42.867868Z","caller":"traceutil/trace.go:171","msg":"trace[2017023740] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"229.231448ms","start":"2026-04-27T19:47:42.638616Z","end":"2026-04-27T19:47:42.867848Z","steps":["trace[2017023740] 'process raft request' (duration: 229.055863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:42.86796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:42.546355Z","time spent":"321.527179ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:42.868122Z","caller":"traceutil/trace.go:171","msg":"trace[603121029] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"322.160535ms","start":"2026-04-27T19:47:42.545947Z","end":"2026-04-27T19:47:42.868107Z","steps":["trace[603121029] 'process raft request' (duration: 321.62273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:42.868209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:42.545938Z","time spent":"322.226036ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:45.696563Z","caller":"traceutil/trace.go:171","msg":"trace[1032499736] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"155.50796ms","start":"2026-04-27T19:47:45.541037Z","end":"2026-04-27T19:47:45.696545Z","steps":["trace[1032499736] 'process raft request' (duration: 155.252414ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:45.973462Z","caller":"traceutil/trace.go:171","msg":"trace[1296873843] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"154.574926ms","start":"2026-04-27T19:47:45.818864Z","end":"2026-04-27T19:47:45.973439Z","steps":["trace[1296873843] 'read index received' (duration: 154.219498ms)","trace[1296873843] 'applied index is now lower than readState.Index' (duration: 354.578µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:45.973529Z","caller":"traceutil/trace.go:171","msg":"trace[1163209971] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"168.015019ms","start":"2026-04-27T19:47:45.805494Z","end":"2026-04-27T19:47:45.973509Z","steps":["trace[1163209971] 'process raft request' (duration: 167.696781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:45.973633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.762671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:47:45.973674Z","caller":"traceutil/trace.go:171","msg":"trace[2027880195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"154.853863ms","start":"2026-04-27T19:47:45.818809Z","end":"2026-04-27T19:47:45.973663Z","steps":["trace[2027880195] 'agreement among raft nodes before linearized reading' (duration: 154.752631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:45.973733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.428031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-27T19:47:45.973798Z","caller":"traceutil/trace.go:171","msg":"trace[343899974] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:721; }","duration":"139.534083ms","start":"2026-04-27T19:47:45.834251Z","end":"2026-04-27T19:47:45.973785Z","steps":["trace[343899974] 'agreement among raft nodes before linearized reading' (duration: 139.359849ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:53.850542Z","caller":"traceutil/trace.go:171","msg":"trace[297837515] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"328.799643ms","start":"2026-04-27T19:47:53.521713Z","end":"2026-04-27T19:47:53.850512Z","steps":["trace[297837515] 'process raft request' (duration: 328.540456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:53.85072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:53.521693Z","time spent":"328.931687ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:55.042328Z","caller":"traceutil/trace.go:171","msg":"trace[2122497381] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"122.640203ms","start":"2026-04-27T19:47:54.919652Z","end":"2026-04-27T19:47:55.042292Z","steps":["trace[2122497381] 'process raft request' (duration: 122.331495ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:55.131898Z","caller":"traceutil/trace.go:171","msg":"trace[457385032] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"210.324547ms","start":"2026-04-27T19:47:54.921546Z","end":"2026-04-27T19:47:55.131871Z","steps":["trace[457385032] 'process raft request' (duration: 180.495617ms)","trace[457385032] 'compare' (duration: 29.678486ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:55.132076Z","caller":"traceutil/trace.go:171","msg":"trace[1248685761] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"209.172077ms","start":"2026-04-27T19:47:54.922879Z","end":"2026-04-27T19:47:55.132051Z","steps":["trace[1248685761] 'process raft request' (duration: 208.954142ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:56.637008Z","caller":"traceutil/trace.go:171","msg":"trace[1672678535] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"109.497448ms","start":"2026-04-27T19:47:56.527481Z","end":"2026-04-27T19:47:56.636979Z","steps":["trace[1672678535] 'process raft request' (duration: 108.842641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:57.545983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948011693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:47:57.66305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:56.968Z","time spent":"695.045391ms","remote":"127.0.0.1:42536","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T19:47:58.826125Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.163170713s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:47:58.826532Z","caller":"traceutil/trace.go:171","msg":"trace[648372662] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:825; }","duration":"1.780592647s","start":"2026-04-27T19:47:57.045893Z","end":"2026-04-27T19:47:58.826485Z","steps":["trace[648372662] 'read index received' (duration: 617.029384ms)","trace[648372662] 'applied index is now lower than readState.Index' (duration: 1.163561733s)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:58.826633Z","caller":"traceutil/trace.go:171","msg":"trace[8841756] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.293545442s","start":"2026-04-27T19:47:57.533078Z","end":"2026-04-27T19:47:58.826623Z","steps":["trace[8841756] 'process raft request' (duration: 1.293350327s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:58.826675Z","caller":"traceutil/trace.go:171","msg":"trace[290347168] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.772452419s","start":"2026-04-27T19:47:57.054204Z","end":"2026-04-27T19:47:58.826657Z","steps":["trace[290347168] 'process raft request' (duration: 1.7720746s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.826719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.533053Z","time spent":"1.293601234s","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:47:58.826781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.054183Z","time spent":"1.772543741s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:47:58.82695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.781054678s","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-27T19:47:58.826976Z","caller":"traceutil/trace.go:171","msg":"trace[1947912634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"1.78110998s","start":"2026-04-27T19:47:57.045859Z","end":"2026-04-27T19:47:58.826969Z","steps":["trace[1947912634] 'agreement among raft nodes before linearized reading' (duration: 1.781021058s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.826998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.045838Z","time spent":"1.781155711s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:47:58.834986Z","caller":"traceutil/trace.go:171","msg":"trace[1232922758] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.170871819s","start":"2026-04-27T19:47:57.664088Z","end":"2026-04-27T19:47:58.83496Z","steps":["trace[1232922758] 'process raft request' (duration: 1.170765076s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.835011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012922467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:47:58.835061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369418556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:47:58.835066Z","caller":"traceutil/trace.go:171","msg":"trace[1681436856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"1.013011068s","start":"2026-04-27T19:47:57.822041Z","end":"2026-04-27T19:47:58.835052Z","steps":["trace[1681436856] 'agreement among raft nodes before linearized reading' (duration: 1.012923216s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:58.835083Z","caller":"traceutil/trace.go:171","msg":"trace[1699406418] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:799; }","duration":"1.369476268s","start":"2026-04-27T19:47:57.465597Z","end":"2026-04-27T19:47:58.835073Z","steps":["trace[1699406418] 'agreement among raft nodes before linearized reading' (duration: 1.369451027s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.835092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.664074Z","time spent":"1.170957662s","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:47:58.835099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.822017Z","time spent":"1.01307547s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:47:58.835159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470174881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-kcrnn\" ","response":"range_response_count:1 size:5049"} {"level":"info","ts":"2026-04-27T19:47:58.835173Z","caller":"traceutil/trace.go:171","msg":"trace[633210521] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-kcrnn; range_end:; response_count:1; response_revision:799; }","duration":"1.470231902s","start":"2026-04-27T19:47:57.364937Z","end":"2026-04-27T19:47:58.835169Z","steps":["trace[633210521] 'agreement among raft nodes before linearized reading' (duration: 1.470176821s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.835185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.364898Z","time spent":"1.470283904s","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5073,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-kcrnn\" "} {"level":"warn","ts":"2026-04-27T19:47:58.835311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698558087s","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-27T19:47:58.835323Z","caller":"traceutil/trace.go:171","msg":"trace[1484588854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.698607139s","start":"2026-04-27T19:47:57.136712Z","end":"2026-04-27T19:47:58.835319Z","steps":["trace[1484588854] 'agreement among raft nodes before linearized reading' (duration: 1.698568898s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.835335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.136683Z","time spent":"1.69864968s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:47:58.83534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.328544ms","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-27T19:47:58.835365Z","caller":"traceutil/trace.go:171","msg":"trace[2117848454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"334.385786ms","start":"2026-04-27T19:47:58.500971Z","end":"2026-04-27T19:47:58.835357Z","steps":["trace[2117848454] 'agreement among raft nodes before linearized reading' (duration: 334.287233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.835384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:58.50095Z","time spent":"334.429486ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:47:58.835482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.458164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:47:58.835501Z","caller":"traceutil/trace.go:171","msg":"trace[1562607234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"957.518615ms","start":"2026-04-27T19:47:57.877977Z","end":"2026-04-27T19:47:58.835495Z","steps":["trace[1562607234] 'agreement among raft nodes before linearized reading' (duration: 957.448343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.835518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.877952Z","time spent":"957.561616ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:47:58.835103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.465568Z","time spent":"1.369530478s","remote":"127.0.0.1:43010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:47:58.835028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698577928s","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-27T19:47:58.835589Z","caller":"traceutil/trace.go:171","msg":"trace[469094935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.699176613s","start":"2026-04-27T19:47:57.136404Z","end":"2026-04-27T19:47:58.835581Z","steps":["trace[469094935] 'agreement among raft nodes before linearized reading' (duration: 1.698535537s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.835626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:57.13638Z","time spent":"1.699236944s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T19:48:02.731979Z","caller":"traceutil/trace.go:171","msg":"trace[96591491] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"120.833167ms","start":"2026-04-27T19:48:02.61112Z","end":"2026-04-27T19:48:02.731953Z","steps":["trace[96591491] 'process raft request' (duration: 114.780773ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:02.739848Z","caller":"traceutil/trace.go:171","msg":"trace[833133744] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"128.546984ms","start":"2026-04-27T19:48:02.611278Z","end":"2026-04-27T19:48:02.739825Z","steps":["trace[833133744] 'process raft request' (duration: 128.40322ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:02.949584Z","caller":"traceutil/trace.go:171","msg":"trace[104943938] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"126.674476ms","start":"2026-04-27T19:48:02.82285Z","end":"2026-04-27T19:48:02.949524Z","steps":["trace[104943938] 'read index received' (duration: 125.823935ms)","trace[104943938] 'applied index is now lower than readState.Index' (duration: 848.211µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:02.949629Z","caller":"traceutil/trace.go:171","msg":"trace[1520083507] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"204.265712ms","start":"2026-04-27T19:48:02.745335Z","end":"2026-04-27T19:48:02.949601Z","steps":["trace[1520083507] 'process raft request' (duration: 203.37516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:02.949769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.909462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:48:02.949983Z","caller":"traceutil/trace.go:171","msg":"trace[1561398850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"127.170038ms","start":"2026-04-27T19:48:02.8228Z","end":"2026-04-27T19:48:02.949971Z","steps":["trace[1561398850] 'agreement among raft nodes before linearized reading' (duration: 126.890141ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:03.519396Z","caller":"traceutil/trace.go:171","msg":"trace[108894407] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"146.996693ms","start":"2026-04-27T19:48:03.372361Z","end":"2026-04-27T19:48:03.519358Z","steps":["trace[108894407] 'process raft request' (duration: 144.827849ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:03.521153Z","caller":"traceutil/trace.go:171","msg":"trace[1771317421] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"108.799962ms","start":"2026-04-27T19:48:03.412332Z","end":"2026-04-27T19:48:03.521132Z","steps":["trace[1771317421] 'process raft request' (duration: 108.706509ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:07.023782Z","caller":"traceutil/trace.go:171","msg":"trace[462593738] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"161.179451ms","start":"2026-04-27T19:48:06.862581Z","end":"2026-04-27T19:48:07.023761Z","steps":["trace[462593738] 'process raft request' (duration: 161.033708ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:10.226916Z","caller":"traceutil/trace.go:171","msg":"trace[676370744] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"126.99448ms","start":"2026-04-27T19:48:10.0999Z","end":"2026-04-27T19:48:10.226895Z","steps":["trace[676370744] 'process raft request' (duration: 126.845287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:15.646136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.370508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:48:15.646269Z","caller":"traceutil/trace.go:171","msg":"trace[1406210964] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"177.600261ms","start":"2026-04-27T19:48:15.468646Z","end":"2026-04-27T19:48:15.646246Z","steps":["trace[1406210964] 'process raft request' (duration: 71.993557ms)","trace[1406210964] 'compare' (duration: 105.244525ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:15.64864Z","caller":"traceutil/trace.go:171","msg":"trace[1723779156] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"179.772176ms","start":"2026-04-27T19:48:15.46885Z","end":"2026-04-27T19:48:15.648622Z","steps":["trace[1723779156] 'process raft request' (duration: 179.680103ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:28.556186Z","caller":"traceutil/trace.go:171","msg":"trace[1518836334] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"199.388346ms","start":"2026-04-27T19:48:28.356776Z","end":"2026-04-27T19:48:28.556164Z","steps":["trace[1518836334] 'process raft request' (duration: 199.242123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:28.908589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.95432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:48:28.90867Z","caller":"traceutil/trace.go:171","msg":"trace[1170073648] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:996; }","duration":"245.077213ms","start":"2026-04-27T19:48:28.663573Z","end":"2026-04-27T19:48:28.90865Z","steps":["trace[1170073648] 'count revisions from in-memory index tree' (duration: 244.901649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:34.287603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948012162,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:48:34.482847Z","caller":"traceutil/trace.go:171","msg":"trace[1706500285] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"695.729089ms","start":"2026-04-27T19:48:33.787097Z","end":"2026-04-27T19:48:34.482826Z","steps":["trace[1706500285] 'read index received' (duration: 695.603566ms)","trace[1706500285] 'applied index is now lower than readState.Index' (duration: 124.823µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:34.482933Z","caller":"traceutil/trace.go:171","msg":"trace[1829051219] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"703.871177ms","start":"2026-04-27T19:48:33.779055Z","end":"2026-04-27T19:48:34.482926Z","steps":["trace[1829051219] 'process raft request' (duration: 703.663202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:34.483003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:33.779033Z","time spent":"703.918839ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:48:34.483103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.099746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:48:34.48314Z","caller":"traceutil/trace.go:171","msg":"trace[1304792325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"661.169198ms","start":"2026-04-27T19:48:33.82196Z","end":"2026-04-27T19:48:34.483129Z","steps":["trace[1304792325] 'agreement among raft nodes before linearized reading' (duration: 661.114177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:34.483163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:33.821942Z","time spent":"661.216809ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:48:34.483338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.246573ms","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-27T19:48:34.483371Z","caller":"traceutil/trace.go:171","msg":"trace[1882981808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"696.311125ms","start":"2026-04-27T19:48:33.787047Z","end":"2026-04-27T19:48:34.483358Z","steps":["trace[1882981808] 'agreement among raft nodes before linearized reading' (duration: 696.186582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:34.483406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:33.787028Z","time spent":"696.368287ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:48:35.211416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948012166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:48:35.711731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948012166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:48:36.212233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948012166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:48:36.461513Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.877978282s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:48:36.465794Z","caller":"traceutil/trace.go:171","msg":"trace[1648581153] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"1.755052494s","start":"2026-04-27T19:48:34.71072Z","end":"2026-04-27T19:48:36.465773Z","steps":["trace[1648581153] 'read index received' (duration: 1.7509533s)","trace[1648581153] 'applied index is now lower than readState.Index' (duration: 4.098234ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:36.466055Z","caller":"traceutil/trace.go:171","msg":"trace[856821331] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.88260701s","start":"2026-04-27T19:48:34.583432Z","end":"2026-04-27T19:48:36.466039Z","steps":["trace[856821331] 'process raft request' (duration: 1.878304091s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.466172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:34.583408Z","time spent":"1.882684661s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:48:36.466442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755719562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:48:36.466477Z","caller":"traceutil/trace.go:171","msg":"trace[1113062772] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1020; }","duration":"1.755784623s","start":"2026-04-27T19:48:34.710682Z","end":"2026-04-27T19:48:36.466467Z","steps":["trace[1113062772] 'agreement among raft nodes before linearized reading' (duration: 1.755723271s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.466507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:34.710666Z","time spent":"1.755833415s","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:48:36.466645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480274326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:48:36.466673Z","caller":"traceutil/trace.go:171","msg":"trace[72463915] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1020; }","duration":"1.480341158s","start":"2026-04-27T19:48:34.986322Z","end":"2026-04-27T19:48:36.466663Z","steps":["trace[72463915] 'agreement among raft nodes before linearized reading' (duration: 1.480296087s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.4667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:34.986299Z","time spent":"1.480392858s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:48:36.466894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.570765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:48:36.466924Z","caller":"traceutil/trace.go:171","msg":"trace[995163135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"646.627657ms","start":"2026-04-27T19:48:35.820288Z","end":"2026-04-27T19:48:36.466916Z","steps":["trace[995163135] 'agreement among raft nodes before linearized reading' (duration: 646.584486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.466948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:35.820273Z","time spent":"646.670019ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:48:36.467138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.103695ms","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-27T19:48:36.467164Z","caller":"traceutil/trace.go:171","msg":"trace[183370300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"815.156637ms","start":"2026-04-27T19:48:35.651999Z","end":"2026-04-27T19:48:36.467156Z","steps":["trace[183370300] 'agreement among raft nodes before linearized reading' (duration: 815.060035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.467188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:35.651984Z","time spent":"815.198718ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:48:36.467384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050774238s","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-27T19:48:36.467433Z","caller":"traceutil/trace.go:171","msg":"trace[163434009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"1.050840849s","start":"2026-04-27T19:48:35.416564Z","end":"2026-04-27T19:48:36.467405Z","steps":["trace[163434009] 'agreement among raft nodes before linearized reading' (duration: 1.050747407s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.467459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:35.416544Z","time spent":"1.050909411s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:48:36.467646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106854408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:48:36.467682Z","caller":"traceutil/trace.go:171","msg":"trace[1323434194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"1.10692026s","start":"2026-04-27T19:48:35.360751Z","end":"2026-04-27T19:48:36.467671Z","steps":["trace[1323434194] 'agreement among raft nodes before linearized reading' (duration: 1.106842648s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.467708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:35.360731Z","time spent":"1.106970492s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:48:36.46776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40245047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:48:36.46787Z","caller":"traceutil/trace.go:171","msg":"trace[1610165474] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1020; }","duration":"1.402602614s","start":"2026-04-27T19:48:35.065246Z","end":"2026-04-27T19:48:36.467849Z","steps":["trace[1610165474] 'agreement among raft nodes before linearized reading' (duration: 1.402417409s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.467928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:35.065232Z","time spent":"1.402683316s","remote":"127.0.0.1:42770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:48:36.986778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948012171,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:48:37.337165Z","caller":"traceutil/trace.go:171","msg":"trace[2139551632] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"863.800487ms","start":"2026-04-27T19:48:36.473343Z","end":"2026-04-27T19:48:37.337143Z","steps":["trace[2139551632] 'process raft request' (duration: 861.826727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:37.337284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:36.473318Z","time spent":"863.90176ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:48:37.344928Z","caller":"traceutil/trace.go:171","msg":"trace[1166512905] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1059; }","duration":"859.145649ms","start":"2026-04-27T19:48:36.48576Z","end":"2026-04-27T19:48:37.344906Z","steps":["trace[1166512905] 'read index received' (duration: 849.420211ms)","trace[1166512905] 'applied index is now lower than readState.Index' (duration: 9.724468ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:37.345165Z","caller":"traceutil/trace.go:171","msg":"trace[1519196245] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"871.536674ms","start":"2026-04-27T19:48:36.473619Z","end":"2026-04-27T19:48:37.345156Z","steps":["trace[1519196245] 'process raft request' (duration: 871.252787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:37.345205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.426986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T19:48:37.345246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.564902ms","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-27T19:48:37.345268Z","caller":"traceutil/trace.go:171","msg":"trace[125431103] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"871.827162ms","start":"2026-04-27T19:48:36.473419Z","end":"2026-04-27T19:48:37.345246Z","steps":["trace[125431103] 'process raft request' (duration: 871.388801ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:37.345304Z","caller":"traceutil/trace.go:171","msg":"trace[1313864175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"816.659385ms","start":"2026-04-27T19:48:36.528631Z","end":"2026-04-27T19:48:37.345291Z","steps":["trace[1313864175] 'agreement among raft nodes before linearized reading' (duration: 816.4752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:37.345345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:36.528617Z","time spent":"816.722076ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:48:37.345362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.350451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.39\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-27T19:48:37.345383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:36.473404Z","time spent":"871.908024ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:48:37.34544Z","caller":"traceutil/trace.go:171","msg":"trace[997616387] range","detail":"{range_begin:/registry/masterleases/199.19.213.39; range_end:; response_count:1; response_revision:1023; }","duration":"376.493114ms","start":"2026-04-27T19:48:36.968934Z","end":"2026-04-27T19:48:37.345427Z","steps":["trace[997616387] 'agreement among raft nodes before linearized reading' (duration: 376.281479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:37.345482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:36.968907Z","time spent":"376.566696ms","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.39\" "} {"level":"info","ts":"2026-04-27T19:48:37.345303Z","caller":"traceutil/trace.go:171","msg":"trace[1438275418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"859.56122ms","start":"2026-04-27T19:48:36.485723Z","end":"2026-04-27T19:48:37.345284Z","steps":["trace[1438275418] 'agreement among raft nodes before linearized reading' (duration: 859.328114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:37.345531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:36.485713Z","time spent":"859.806816ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:48:37.345243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:36.473605Z","time spent":"871.581696ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:48:47.738523Z","caller":"traceutil/trace.go:171","msg":"trace[575137662] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"195.22432ms","start":"2026-04-27T19:48:47.54327Z","end":"2026-04-27T19:48:47.738494Z","steps":["trace[575137662] 'read index received' (duration: 108.955009ms)","trace[575137662] 'applied index is now lower than readState.Index' (duration: 86.263911ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:47.738611Z","caller":"traceutil/trace.go:171","msg":"trace[733433976] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"197.861657ms","start":"2026-04-27T19:48:47.540715Z","end":"2026-04-27T19:48:47.738577Z","steps":["trace[733433976] 'process raft request' (duration: 111.486133ms)","trace[733433976] 'compare' (duration: 86.062366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:48:47.738901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.60716ms","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-27T19:48:47.73897Z","caller":"traceutil/trace.go:171","msg":"trace[194835802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"195.728874ms","start":"2026-04-27T19:48:47.543227Z","end":"2026-04-27T19:48:47.738956Z","steps":["trace[194835802] 'agreement among raft nodes before linearized reading' (duration: 195.368365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:47.738979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.63857ms","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-27T19:48:47.739067Z","caller":"traceutil/trace.go:171","msg":"trace[1337629675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"195.756714ms","start":"2026-04-27T19:48:47.54328Z","end":"2026-04-27T19:48:47.739037Z","steps":["trace[1337629675] 'agreement among raft nodes before linearized reading' (duration: 195.539988ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:47.739623Z","caller":"traceutil/trace.go:171","msg":"trace[424388342] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"195.640791ms","start":"2026-04-27T19:48:47.543306Z","end":"2026-04-27T19:48:47.738947Z","steps":["trace[424388342] 'process raft request' (duration: 195.427405ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:48.61475Z","caller":"traceutil/trace.go:171","msg":"trace[1269464431] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"100.661649ms","start":"2026-04-27T19:48:48.514048Z","end":"2026-04-27T19:48:48.61471Z","steps":["trace[1269464431] 'process raft request' (duration: 100.534565ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:59.15186Z","caller":"traceutil/trace.go:171","msg":"trace[1121363463] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"329.636219ms","start":"2026-04-27T19:48:58.822152Z","end":"2026-04-27T19:48:59.151789Z","steps":["trace[1121363463] 'read index received' (duration: 329.2993ms)","trace[1121363463] 'applied index is now lower than readState.Index' (duration: 335.529µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:59.151909Z","caller":"traceutil/trace.go:171","msg":"trace[61320043] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"446.239905ms","start":"2026-04-27T19:48:58.705633Z","end":"2026-04-27T19:48:59.151873Z","steps":["trace[61320043] 'process raft request' (duration: 445.927657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:59.152007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.825364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:48:59.152079Z","caller":"traceutil/trace.go:171","msg":"trace[1526966586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"329.954738ms","start":"2026-04-27T19:48:58.822109Z","end":"2026-04-27T19:48:59.152064Z","steps":["trace[1526966586] 'agreement among raft nodes before linearized reading' (duration: 329.833595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:59.152081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:58.705593Z","time spent":"446.393559ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:48:59.152134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:58.822087Z","time spent":"330.034049ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:49:00.37832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.011769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T19:49:00.378366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.12398ms","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-27T19:49:00.378419Z","caller":"traceutil/trace.go:171","msg":"trace[215370963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"504.157672ms","start":"2026-04-27T19:48:59.874239Z","end":"2026-04-27T19:49:00.378397Z","steps":["trace[215370963] 'range keys from in-memory index tree' (duration: 503.827854ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:00.37848Z","caller":"traceutil/trace.go:171","msg":"trace[934880413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"805.212573ms","start":"2026-04-27T19:48:59.573194Z","end":"2026-04-27T19:49:00.378407Z","steps":["trace[934880413] 'range keys from in-memory index tree' (duration: 804.992647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:00.378549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:59.573175Z","time spent":"805.357256ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:49:00.37883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.282279ms","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-27T19:49:00.37888Z","caller":"traceutil/trace.go:171","msg":"trace[1259445306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"452.35679ms","start":"2026-04-27T19:48:59.92651Z","end":"2026-04-27T19:49:00.378867Z","steps":["trace[1259445306] 'range keys from in-memory index tree' (duration: 452.154036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:00.37892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:59.926494Z","time spent":"452.415422ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:49:00.378945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.612146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T19:49:00.378966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.102677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:49:00.378961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.122557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:49:00.379062Z","caller":"traceutil/trace.go:171","msg":"trace[1968592804] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1093; }","duration":"761.237001ms","start":"2026-04-27T19:48:59.617811Z","end":"2026-04-27T19:49:00.379048Z","steps":["trace[1968592804] 'count revisions from in-memory index tree' (duration: 761.030545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:00.379111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:59.617791Z","time spent":"761.304112ms","remote":"127.0.0.1:42600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:49:00.3791Z","caller":"traceutil/trace.go:171","msg":"trace[1142935008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1093; }","duration":"594.289752ms","start":"2026-04-27T19:48:59.784793Z","end":"2026-04-27T19:49:00.379083Z","steps":["trace[1142935008] 'range keys from in-memory index tree' (duration: 593.978634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:00.379167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:59.784772Z","time spent":"594.379474ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:49:00.378497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:59.874214Z","time spent":"504.255435ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:49:00.37906Z","caller":"traceutil/trace.go:171","msg":"trace[1159546912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"586.731988ms","start":"2026-04-27T19:48:59.792296Z","end":"2026-04-27T19:49:00.379028Z","steps":["trace[1159546912] 'range keys from in-memory index tree' (duration: 586.40779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:00.379847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:59.792286Z","time spent":"587.52865ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T19:49:00.379948Z","caller":"traceutil/trace.go:171","msg":"trace[383118542] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"169.556337ms","start":"2026-04-27T19:49:00.210367Z","end":"2026-04-27T19:49:00.379923Z","steps":["trace[383118542] 'process raft request' (duration: 155.259952ms)","trace[383118542] 'compare' (duration: 13.767041ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:00.539911Z","caller":"traceutil/trace.go:171","msg":"trace[1559199056] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"155.113128ms","start":"2026-04-27T19:49:00.38477Z","end":"2026-04-27T19:49:00.539884Z","steps":["trace[1559199056] 'process raft request' (duration: 98.629967ms)","trace[1559199056] 'compare' (duration: 56.392758ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:00.545092Z","caller":"traceutil/trace.go:171","msg":"trace[1362855313] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"160.267288ms","start":"2026-04-27T19:49:00.384812Z","end":"2026-04-27T19:49:00.545079Z","steps":["trace[1362855313] 'process raft request' (duration: 160.169706ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:00.545271Z","caller":"traceutil/trace.go:171","msg":"trace[215082888] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"160.265749ms","start":"2026-04-27T19:49:00.384996Z","end":"2026-04-27T19:49:00.545262Z","steps":["trace[215082888] 'process raft request' (duration: 160.053384ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:00.545449Z","caller":"traceutil/trace.go:171","msg":"trace[1497327236] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"159.430537ms","start":"2026-04-27T19:49:00.38601Z","end":"2026-04-27T19:49:00.545441Z","steps":["trace[1497327236] 'process raft request' (duration: 159.398147ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:00.545518Z","caller":"traceutil/trace.go:171","msg":"trace[2033270560] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"160.448543ms","start":"2026-04-27T19:49:00.385062Z","end":"2026-04-27T19:49:00.545511Z","steps":["trace[2033270560] 'process raft request' (duration: 160.168136ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:01.3232Z","caller":"traceutil/trace.go:171","msg":"trace[1803052124] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"166.538029ms","start":"2026-04-27T19:49:01.156623Z","end":"2026-04-27T19:49:01.323161Z","steps":["trace[1803052124] 'process raft request' (duration: 166.385765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:02.244359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.860127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:49:02.244415Z","caller":"traceutil/trace.go:171","msg":"trace[1403669348] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1100; }","duration":"763.96762ms","start":"2026-04-27T19:49:01.480435Z","end":"2026-04-27T19:49:02.244403Z","steps":["trace[1403669348] 'count revisions from in-memory index tree' (duration: 763.745104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:02.244445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:01.480415Z","time spent":"764.022261ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:49:02.244445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.028029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:02.244524Z","caller":"traceutil/trace.go:171","msg":"trace[1301539343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1100; }","duration":"682.122372ms","start":"2026-04-27T19:49:01.562358Z","end":"2026-04-27T19:49:02.244481Z","steps":["trace[1301539343] 'count revisions from in-memory index tree' (duration: 681.826735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:02.244571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:01.562337Z","time spent":"682.222955ms","remote":"127.0.0.1:34078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:49:02.559278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.424412ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-27T19:49:02.559737Z","caller":"traceutil/trace.go:171","msg":"trace[1637412744] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1144; }","duration":"171.27536ms","start":"2026-04-27T19:49:02.388439Z","end":"2026-04-27T19:49:02.559714Z","steps":["trace[1637412744] 'read index received' (duration: 80.122764ms)","trace[1637412744] 'applied index is now lower than readState.Index' (duration: 91.151205ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:02.559792Z","caller":"traceutil/trace.go:171","msg":"trace[661824389] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"347.697681ms","start":"2026-04-27T19:49:02.21207Z","end":"2026-04-27T19:49:02.559768Z","steps":["trace[661824389] 'process raft request' (duration: 347.333552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:02.559898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.443503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:02.559972Z","caller":"traceutil/trace.go:171","msg":"trace[1905581867] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1101; }","duration":"171.559736ms","start":"2026-04-27T19:49:02.388392Z","end":"2026-04-27T19:49:02.559952Z","steps":["trace[1905581867] 'agreement among raft nodes before linearized reading' (duration: 171.439593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:02.560131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:02.21205Z","time spent":"347.798883ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:02.733729Z","caller":"traceutil/trace.go:171","msg":"trace[1046240586] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"169.075063ms","start":"2026-04-27T19:49:02.564608Z","end":"2026-04-27T19:49:02.733683Z","steps":["trace[1046240586] 'process raft request' (duration: 109.149974ms)","trace[1046240586] 'compare' (duration: 59.461037ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:02.734113Z","caller":"traceutil/trace.go:171","msg":"trace[1180934756] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"169.383941ms","start":"2026-04-27T19:49:02.564707Z","end":"2026-04-27T19:49:02.734091Z","steps":["trace[1180934756] 'process raft request' (duration: 169.050283ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:02.734693Z","caller":"traceutil/trace.go:171","msg":"trace[29367238] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"169.546105ms","start":"2026-04-27T19:49:02.565105Z","end":"2026-04-27T19:49:02.734651Z","steps":["trace[29367238] 'process raft request' (duration: 169.513314ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:02.735014Z","caller":"traceutil/trace.go:171","msg":"trace[1163109178] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"170.286104ms","start":"2026-04-27T19:49:02.564716Z","end":"2026-04-27T19:49:02.735002Z","steps":["trace[1163109178] 'process raft request' (duration: 169.643498ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:03.797431Z","caller":"traceutil/trace.go:171","msg":"trace[410937056] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"469.053857ms","start":"2026-04-27T19:49:03.328347Z","end":"2026-04-27T19:49:03.797401Z","steps":["trace[410937056] 'process raft request' (duration: 468.825091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:03.797672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:03.328327Z","time spent":"469.17865ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:03.797819Z","caller":"traceutil/trace.go:171","msg":"trace[395474254] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1152; }","duration":"469.051887ms","start":"2026-04-27T19:49:03.328748Z","end":"2026-04-27T19:49:03.797792Z","steps":["trace[395474254] 'read index received' (duration: 469.038626ms)","trace[395474254] 'applied index is now lower than readState.Index' (duration: 4.541µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:03.797913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.163909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:03.797951Z","caller":"traceutil/trace.go:171","msg":"trace[1232777678] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1107; }","duration":"469.245261ms","start":"2026-04-27T19:49:03.328696Z","end":"2026-04-27T19:49:03.797941Z","steps":["trace[1232777678] 'agreement among raft nodes before linearized reading' (duration: 469.17689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:03.797988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:03.328676Z","time spent":"469.303543ms","remote":"127.0.0.1:42862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:49:05.140633Z","caller":"traceutil/trace.go:171","msg":"trace[442428378] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"421.161084ms","start":"2026-04-27T19:49:04.719452Z","end":"2026-04-27T19:49:05.140614Z","steps":["trace[442428378] 'read index received' (duration: 421.00873ms)","trace[442428378] 'applied index is now lower than readState.Index' (duration: 151.584µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:05.140798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.332459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:49:05.140827Z","caller":"traceutil/trace.go:171","msg":"trace[83167927] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1108; }","duration":"421.423661ms","start":"2026-04-27T19:49:04.719395Z","end":"2026-04-27T19:49:05.140819Z","steps":["trace[83167927] 'agreement among raft nodes before linearized reading' (duration: 421.344579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:05.140871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:04.719372Z","time spent":"421.492712ms","remote":"127.0.0.1:42822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:49:05.14076Z","caller":"traceutil/trace.go:171","msg":"trace[611598524] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"558.666062ms","start":"2026-04-27T19:49:04.582019Z","end":"2026-04-27T19:49:05.140685Z","steps":["trace[611598524] 'process raft request' (duration: 558.446727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:05.141064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.721293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T19:49:05.141131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.650301ms","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-27T19:49:05.141162Z","caller":"traceutil/trace.go:171","msg":"trace[1074522871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1108; }","duration":"402.721533ms","start":"2026-04-27T19:49:04.738432Z","end":"2026-04-27T19:49:05.141154Z","steps":["trace[1074522871] 'agreement among raft nodes before linearized reading' (duration: 402.6055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:05.141192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:04.73841Z","time spent":"402.776295ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:49:05.141228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.565729ms","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-27T19:49:05.141255Z","caller":"traceutil/trace.go:171","msg":"trace[1598911152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1108; }","duration":"402.609491ms","start":"2026-04-27T19:49:04.738639Z","end":"2026-04-27T19:49:05.141249Z","steps":["trace[1598911152] 'agreement among raft nodes before linearized reading' (duration: 402.543219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:05.141274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:04.738631Z","time spent":"402.639021ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T19:49:05.141182Z","caller":"traceutil/trace.go:171","msg":"trace[471613310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1108; }","duration":"402.840686ms","start":"2026-04-27T19:49:04.7383Z","end":"2026-04-27T19:49:05.141141Z","steps":["trace[471613310] 'agreement among raft nodes before linearized reading' (duration: 402.60721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:05.141323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.96089ms","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-27T19:49:05.141364Z","caller":"traceutil/trace.go:171","msg":"trace[900614745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1108; }","duration":"403.040921ms","start":"2026-04-27T19:49:04.738314Z","end":"2026-04-27T19:49:05.141355Z","steps":["trace[900614745] 'agreement among raft nodes before linearized reading' (duration: 402.894188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:05.141304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:04.582007Z","time spent":"559.111803ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:05.141449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:04.738304Z","time spent":"403.113323ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:49:05.141334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:04.738288Z","time spent":"403.041042ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T19:49:05.752489Z","caller":"traceutil/trace.go:171","msg":"trace[363269377] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"607.48901ms","start":"2026-04-27T19:49:05.144977Z","end":"2026-04-27T19:49:05.752466Z","steps":["trace[363269377] 'process raft request' (duration: 607.326356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:05.75274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:05.144961Z","time spent":"607.701355ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:06.127585Z","caller":"traceutil/trace.go:171","msg":"trace[756282622] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1154; }","duration":"304.832386ms","start":"2026-04-27T19:49:05.82273Z","end":"2026-04-27T19:49:06.127563Z","steps":["trace[756282622] 'read index received' (duration: 221.389938ms)","trace[756282622] 'applied index is now lower than readState.Index' (duration: 83.441268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:06.127715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.96555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:06.127747Z","caller":"traceutil/trace.go:171","msg":"trace[1009811376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"305.036813ms","start":"2026-04-27T19:49:05.822701Z","end":"2026-04-27T19:49:06.127738Z","steps":["trace[1009811376] 'agreement among raft nodes before linearized reading' (duration: 304.94734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:06.127778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:05.82269Z","time spent":"305.081723ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:49:06.127859Z","caller":"traceutil/trace.go:171","msg":"trace[161898032] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"980.979378ms","start":"2026-04-27T19:49:05.146774Z","end":"2026-04-27T19:49:06.127753Z","steps":["trace[161898032] 'process raft request' (duration: 980.636098ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:06.127944Z","caller":"traceutil/trace.go:171","msg":"trace[8567028] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"979.452199ms","start":"2026-04-27T19:49:05.148463Z","end":"2026-04-27T19:49:06.127915Z","steps":["trace[8567028] 'process raft request' (duration: 978.991546ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:06.127981Z","caller":"traceutil/trace.go:171","msg":"trace[2114718413] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"323.499513ms","start":"2026-04-27T19:49:05.804469Z","end":"2026-04-27T19:49:06.127969Z","steps":["trace[2114718413] 'process raft request' (duration: 323.052561ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:06.12798Z","caller":"traceutil/trace.go:171","msg":"trace[698632083] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"981.312267ms","start":"2026-04-27T19:49:05.146633Z","end":"2026-04-27T19:49:06.127946Z","steps":["trace[698632083] 'process raft request' (duration: 897.471158ms)","trace[698632083] 'compare' (duration: 83.178841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:06.128063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:05.804441Z","time spent":"323.565215ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:06.128112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:05.146766Z","time spent":"981.187044ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:06.128089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:05.148453Z","time spent":"979.555451ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:06.128175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:05.146621Z","time spent":"981.43752ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:17.421892Z","caller":"traceutil/trace.go:171","msg":"trace[942723657] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"146.330134ms","start":"2026-04-27T19:49:17.275528Z","end":"2026-04-27T19:49:17.421859Z","steps":["trace[942723657] 'read index received' (duration: 146.080358ms)","trace[942723657] 'applied index is now lower than readState.Index' (duration: 248.256µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:17.421937Z","caller":"traceutil/trace.go:171","msg":"trace[1247130087] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"189.345072ms","start":"2026-04-27T19:49:17.23255Z","end":"2026-04-27T19:49:17.421895Z","steps":["trace[1247130087] 'process raft request' (duration: 189.115936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:17.422315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.753385ms","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-27T19:49:17.422366Z","caller":"traceutil/trace.go:171","msg":"trace[1324035263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"146.876629ms","start":"2026-04-27T19:49:17.275476Z","end":"2026-04-27T19:49:17.422353Z","steps":["trace[1324035263] 'agreement among raft nodes before linearized reading' (duration: 146.511059ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:17.608746Z","caller":"traceutil/trace.go:171","msg":"trace[6363072] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"106.311593ms","start":"2026-04-27T19:49:17.502407Z","end":"2026-04-27T19:49:17.608718Z","steps":["trace[6363072] 'process raft request' (duration: 65.116001ms)","trace[6363072] 'compare' (duration: 40.945766ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:17.767757Z","caller":"traceutil/trace.go:171","msg":"trace[1984753728] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"154.870033ms","start":"2026-04-27T19:49:17.61286Z","end":"2026-04-27T19:49:17.76773Z","steps":["trace[1984753728] 'process raft request' (duration: 135.629712ms)","trace[1984753728] 'compare' (duration: 18.968664ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:17.77104Z","caller":"traceutil/trace.go:171","msg":"trace[827999142] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"157.89216ms","start":"2026-04-27T19:49:17.613126Z","end":"2026-04-27T19:49:17.771018Z","steps":["trace[827999142] 'process raft request' (duration: 157.853919ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:17.771302Z","caller":"traceutil/trace.go:171","msg":"trace[1451453235] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"158.232168ms","start":"2026-04-27T19:49:17.613047Z","end":"2026-04-27T19:49:17.771279Z","steps":["trace[1451453235] 'process raft request' (duration: 157.574202ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:21.986944Z","caller":"traceutil/trace.go:171","msg":"trace[353911580] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"166.053158ms","start":"2026-04-27T19:49:21.82087Z","end":"2026-04-27T19:49:21.986923Z","steps":["trace[353911580] 'read index received' (duration: 165.889564ms)","trace[353911580] 'applied index is now lower than readState.Index' (duration: 162.704µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:21.987057Z","caller":"traceutil/trace.go:171","msg":"trace[812541076] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"477.426814ms","start":"2026-04-27T19:49:21.50962Z","end":"2026-04-27T19:49:21.987047Z","steps":["trace[812541076] 'process raft request' (duration: 477.183968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:21.987215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:21.509523Z","time spent":"477.558878ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:21.987355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.84043ms","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-27T19:49:21.987418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.53627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:21.987444Z","caller":"traceutil/trace.go:171","msg":"trace[1837548051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"161.962573ms","start":"2026-04-27T19:49:21.825462Z","end":"2026-04-27T19:49:21.987424Z","steps":["trace[1837548051] 'agreement among raft nodes before linearized reading' (duration: 161.744248ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:21.987476Z","caller":"traceutil/trace.go:171","msg":"trace[1606033186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"166.630462ms","start":"2026-04-27T19:49:21.820829Z","end":"2026-04-27T19:49:21.987459Z","steps":["trace[1606033186] 'agreement among raft nodes before linearized reading' (duration: 166.53815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:21.987746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.769279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:21.987898Z","caller":"traceutil/trace.go:171","msg":"trace[2070929825] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1221; }","duration":"108.032976ms","start":"2026-04-27T19:49:21.87985Z","end":"2026-04-27T19:49:21.987883Z","steps":["trace[2070929825] 'agreement among raft nodes before linearized reading' (duration: 107.535713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:24.492578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948012643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:49:24.504588Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.531959795s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T19:49:25.125472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.455904785s","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-27T19:49:25.125654Z","caller":"traceutil/trace.go:171","msg":"trace[909240131] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1226; }","duration":"2.45611035s","start":"2026-04-27T19:49:22.669519Z","end":"2026-04-27T19:49:25.125629Z","steps":["trace[909240131] 'range keys from in-memory index tree' (duration: 2.455876925s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.125686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.302065849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:25.12577Z","caller":"traceutil/trace.go:171","msg":"trace[1984755843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"2.302205962s","start":"2026-04-27T19:49:22.823543Z","end":"2026-04-27T19:49:25.125749Z","steps":["trace[1984755843] 'range keys from in-memory index tree' (duration: 2.301970706s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.125813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:22.823523Z","time spent":"2.302280964s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:49:25.12683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.84507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:49:25.127048Z","caller":"traceutil/trace.go:171","msg":"trace[2128938486] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1277; }","duration":"1.135596111s","start":"2026-04-27T19:49:23.991434Z","end":"2026-04-27T19:49:25.12703Z","steps":["trace[2128938486] 'read index received' (duration: 513.397123ms)","trace[2128938486] 'applied index is now lower than readState.Index' (duration: 622.195628ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:25.127333Z","caller":"traceutil/trace.go:171","msg":"trace[1008334879] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"2.154750539s","start":"2026-04-27T19:49:22.972559Z","end":"2026-04-27T19:49:25.127309Z","steps":["trace[1008334879] 'process raft request' (duration: 1.532258163s)","trace[1008334879] 'compare' (duration: 621.181982ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:25.127418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:22.972542Z","time spent":"2.154833141s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:25.127692Z","caller":"traceutil/trace.go:171","msg":"trace[461929643] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"928.399404ms","start":"2026-04-27T19:49:24.19927Z","end":"2026-04-27T19:49:25.127669Z","steps":["trace[461929643] 'process raft request' (duration: 927.668765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.127802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:24.19925Z","time spent":"928.490095ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:25.12811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136669688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:49:25.128146Z","caller":"traceutil/trace.go:171","msg":"trace[712299845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1228; }","duration":"1.136711789s","start":"2026-04-27T19:49:23.991422Z","end":"2026-04-27T19:49:25.128134Z","steps":["trace[712299845] 'agreement among raft nodes before linearized reading' (duration: 1.136577476s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.12818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:23.991384Z","time spent":"1.136788481s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:49:25.128314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130635603s","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-27T19:49:25.128418Z","caller":"traceutil/trace.go:171","msg":"trace[1439267221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"1.130757047s","start":"2026-04-27T19:49:23.997637Z","end":"2026-04-27T19:49:25.128394Z","steps":["trace[1439267221] 'agreement among raft nodes before linearized reading' (duration: 1.1305014s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.128438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.204873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:49:25.128588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.202597ms","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-27T19:49:25.128496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:23.997632Z","time spent":"1.130849999s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T19:49:25.128652Z","caller":"traceutil/trace.go:171","msg":"trace[275260837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1228; }","duration":"640.30671ms","start":"2026-04-27T19:49:24.488331Z","end":"2026-04-27T19:49:25.128638Z","steps":["trace[275260837] 'agreement among raft nodes before linearized reading' (duration: 640.222418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.128685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:24.488309Z","time spent":"640.366922ms","remote":"127.0.0.1:33966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:49:25.128903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.634603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:25.12895Z","caller":"traceutil/trace.go:171","msg":"trace[598991868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"304.725166ms","start":"2026-04-27T19:49:24.82421Z","end":"2026-04-27T19:49:25.128935Z","steps":["trace[598991868] 'agreement among raft nodes before linearized reading' (duration: 304.650065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.128988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:24.824192Z","time spent":"304.787048ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:49:25.129161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.61783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T19:49:25.129203Z","caller":"traceutil/trace.go:171","msg":"trace[59471402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"816.718752ms","start":"2026-04-27T19:49:24.312472Z","end":"2026-04-27T19:49:25.129191Z","steps":["trace[59471402] 'agreement among raft nodes before linearized reading' (duration: 816.433695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.129202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.238662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T19:49:25.129234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:24.312449Z","time spent":"816.778024ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:49:25.129284Z","caller":"traceutil/trace.go:171","msg":"trace[2077213932] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1228; }","duration":"898.355975ms","start":"2026-04-27T19:49:24.230914Z","end":"2026-04-27T19:49:25.12927Z","steps":["trace[2077213932] 'agreement among raft nodes before linearized reading' (duration: 898.201771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.129342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:24.230873Z","time spent":"898.457697ms","remote":"127.0.0.1:42934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:49:25.12859Z","caller":"traceutil/trace.go:171","msg":"trace[665705447] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1228; }","duration":"369.475739ms","start":"2026-04-27T19:49:24.759087Z","end":"2026-04-27T19:49:25.128563Z","steps":["trace[665705447] 'agreement among raft nodes before linearized reading' (duration: 369.216653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.129447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:24.759056Z","time spent":"370.372863ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:49:25.129686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.667087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-27T19:49:25.129698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.010695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T19:49:25.129731Z","caller":"traceutil/trace.go:171","msg":"trace[1634696885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"817.889342ms","start":"2026-04-27T19:49:24.311829Z","end":"2026-04-27T19:49:25.129718Z","steps":["trace[1634696885] 'agreement among raft nodes before linearized reading' (duration: 817.632566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.129763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:24.311806Z","time spent":"817.948554ms","remote":"127.0.0.1:42784","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-27T19:49:25.129759Z","caller":"traceutil/trace.go:171","msg":"trace[2068218995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"836.081307ms","start":"2026-04-27T19:49:24.293649Z","end":"2026-04-27T19:49:25.12973Z","steps":["trace[2068218995] 'agreement among raft nodes before linearized reading' (duration: 835.918123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:25.12983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:24.293634Z","time spent":"836.170909ms","remote":"127.0.0.1:42784","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-27T19:49:37.708962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.992713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:49:37.70918Z","caller":"traceutil/trace.go:171","msg":"trace[1787355160] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"397.620827ms","start":"2026-04-27T19:49:37.311544Z","end":"2026-04-27T19:49:37.709165Z","steps":["trace[1787355160] 'process raft request' (duration: 397.593577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:37.709244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:37.311525Z","time spent":"397.691339ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:37.709302Z","caller":"traceutil/trace.go:171","msg":"trace[419499737] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"415.185555ms","start":"2026-04-27T19:49:37.294088Z","end":"2026-04-27T19:49:37.709274Z","steps":["trace[419499737] 'process raft request' (duration: 78.800622ms)","trace[419499737] 'compare' (duration: 335.826749ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:37.709396Z","caller":"traceutil/trace.go:171","msg":"trace[608677543] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"413.826801ms","start":"2026-04-27T19:49:37.295562Z","end":"2026-04-27T19:49:37.709388Z","steps":["trace[608677543] 'process raft request' (duration: 413.493833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:37.709442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:37.295553Z","time spent":"413.869153ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:37.709445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:37.294074Z","time spent":"415.286178ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:39.834905Z","caller":"traceutil/trace.go:171","msg":"trace[867654138] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"119.07738ms","start":"2026-04-27T19:49:39.715803Z","end":"2026-04-27T19:49:39.83488Z","steps":["trace[867654138] 'process raft request' (duration: 118.931866ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:39.866697Z","caller":"traceutil/trace.go:171","msg":"trace[1405640719] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"149.354781ms","start":"2026-04-27T19:49:39.717317Z","end":"2026-04-27T19:49:39.866672Z","steps":["trace[1405640719] 'process raft request' (duration: 149.111005ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:39.866852Z","caller":"traceutil/trace.go:171","msg":"trace[639213296] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"149.112955ms","start":"2026-04-27T19:49:39.71772Z","end":"2026-04-27T19:49:39.866833Z","steps":["trace[639213296] 'process raft request' (duration: 148.90893ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:41.743762Z","caller":"traceutil/trace.go:171","msg":"trace[777450485] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"446.73628ms","start":"2026-04-27T19:49:41.296999Z","end":"2026-04-27T19:49:41.743735Z","steps":["trace[777450485] 'read index received' (duration: 446.585006ms)","trace[777450485] 'applied index is now lower than readState.Index' (duration: 150.144µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:41.743913Z","caller":"traceutil/trace.go:171","msg":"trace[1557198793] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"504.953977ms","start":"2026-04-27T19:49:41.238922Z","end":"2026-04-27T19:49:41.743876Z","steps":["trace[1557198793] 'process raft request' (duration: 504.646049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:41.74402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.996267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:49:41.74406Z","caller":"traceutil/trace.go:171","msg":"trace[2114120630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"447.091489ms","start":"2026-04-27T19:49:41.296957Z","end":"2026-04-27T19:49:41.744048Z","steps":["trace[2114120630] 'agreement among raft nodes before linearized reading' (duration: 446.899944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:41.744098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:41.296941Z","time spent":"447.14795ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:49:41.74411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:41.2389Z","time spent":"505.11556ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:41.76567Z","caller":"traceutil/trace.go:171","msg":"trace[1481440764] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"317.454561ms","start":"2026-04-27T19:49:41.448192Z","end":"2026-04-27T19:49:41.765647Z","steps":["trace[1481440764] 'process raft request' (duration: 311.211062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:41.765805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:41.448176Z","time spent":"317.559794ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:41.765868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.71378ms","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-27T19:49:41.765926Z","caller":"traceutil/trace.go:171","msg":"trace[1227768087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1300; }","duration":"444.804262ms","start":"2026-04-27T19:49:41.321109Z","end":"2026-04-27T19:49:41.765913Z","steps":["trace[1227768087] 'agreement among raft nodes before linearized reading' (duration: 444.560156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:41.765966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:41.321097Z","time spent":"444.861653ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:49:47.489675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:46.972597Z","time spent":"517.074515ms","remote":"127.0.0.1:42536","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T19:49:47.736859Z","caller":"traceutil/trace.go:171","msg":"trace[69192155] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"246.033089ms","start":"2026-04-27T19:49:47.490783Z","end":"2026-04-27T19:49:47.736816Z","steps":["trace[69192155] 'process raft request' (duration: 244.469538ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:47.736857Z","caller":"traceutil/trace.go:171","msg":"trace[1268649452] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"118.033184ms","start":"2026-04-27T19:49:47.61877Z","end":"2026-04-27T19:49:47.736803Z","steps":["trace[1268649452] 'read index received' (duration: 116.421512ms)","trace[1268649452] 'applied index is now lower than readState.Index' (duration: 1.609801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:47.737196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.397342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-27T19:49:47.737269Z","caller":"traceutil/trace.go:171","msg":"trace[686064395] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1358; }","duration":"118.523556ms","start":"2026-04-27T19:49:47.61872Z","end":"2026-04-27T19:49:47.737243Z","steps":["trace[686064395] 'agreement among raft nodes before linearized reading' (duration: 118.204108ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:47.961279Z","caller":"traceutil/trace.go:171","msg":"trace[601679178] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"139.171072ms","start":"2026-04-27T19:49:47.822065Z","end":"2026-04-27T19:49:47.961236Z","steps":["trace[601679178] 'process raft request' (duration: 74.527552ms)","trace[601679178] 'compare' (duration: 64.438464ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:47.961304Z","caller":"traceutil/trace.go:171","msg":"trace[347939666] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"138.204247ms","start":"2026-04-27T19:49:47.823059Z","end":"2026-04-27T19:49:47.961263Z","steps":["trace[347939666] 'process raft request' (duration: 138.105725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:49.239121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339933948012941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:49:49.295259Z","caller":"traceutil/trace.go:171","msg":"trace[311157454] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"556.474171ms","start":"2026-04-27T19:49:48.738759Z","end":"2026-04-27T19:49:49.295233Z","steps":["trace[311157454] 'read index received' (duration: 556.265466ms)","trace[311157454] 'applied index is now lower than readState.Index' (duration: 207.415µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:49.295376Z","caller":"traceutil/trace.go:171","msg":"trace[1726215126] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"560.109735ms","start":"2026-04-27T19:49:48.735253Z","end":"2026-04-27T19:49:49.295363Z","steps":["trace[1726215126] 'process raft request' (duration: 559.819487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:49.295477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.235307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:49:49.295511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:48.735235Z","time spent":"560.175006ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:49.295621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.837981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-27T19:49:49.295649Z","caller":"traceutil/trace.go:171","msg":"trace[1543210502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"473.467283ms","start":"2026-04-27T19:49:48.822166Z","end":"2026-04-27T19:49:49.295634Z","steps":["trace[1543210502] 'agreement among raft nodes before linearized reading' (duration: 473.243228ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:49.295703Z","caller":"traceutil/trace.go:171","msg":"trace[270281678] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"556.974625ms","start":"2026-04-27T19:49:48.738708Z","end":"2026-04-27T19:49:49.295682Z","steps":["trace[270281678] 'agreement among raft nodes before linearized reading' (duration: 556.756439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:49.295705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.38123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:49:49.295739Z","caller":"traceutil/trace.go:171","msg":"trace[1136277058] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"422.450922ms","start":"2026-04-27T19:49:48.873279Z","end":"2026-04-27T19:49:49.29573Z","steps":["trace[1136277058] 'agreement among raft nodes before linearized reading' (duration: 422.339609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:49.295777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:48.873231Z","time spent":"422.538944ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-27T19:49:49.295743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:48.738676Z","time spent":"557.060286ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-27T19:49:49.296237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:48.822104Z","time spent":"473.586676ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:49:50.059322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.399509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:49:50.05948Z","caller":"traceutil/trace.go:171","msg":"trace[528872722] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1423; }","duration":"755.226223ms","start":"2026-04-27T19:49:49.304237Z","end":"2026-04-27T19:49:50.059463Z","steps":["trace[528872722] 'read index received' (duration: 209.609479ms)","trace[528872722] 'applied index is now lower than readState.Index' (duration: 545.615884ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:50.059682Z","caller":"traceutil/trace.go:171","msg":"trace[307610968] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"759.649855ms","start":"2026-04-27T19:49:49.300019Z","end":"2026-04-27T19:49:50.059669Z","steps":["trace[307610968] 'process raft request' (duration: 213.810146ms)","trace[307610968] 'compare' (duration: 544.860894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:50.059752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.300003Z","time spent":"759.715978ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:50.059913Z","caller":"traceutil/trace.go:171","msg":"trace[1534638663] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"758.868156ms","start":"2026-04-27T19:49:49.301036Z","end":"2026-04-27T19:49:50.059904Z","steps":["trace[1534638663] 'process raft request' (duration: 758.375644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.059972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.301011Z","time spent":"758.931827ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:50.060209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.974442ms","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:529"} {"level":"info","ts":"2026-04-27T19:49:50.060245Z","caller":"traceutil/trace.go:171","msg":"trace[542978545] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1369; }","duration":"756.047484ms","start":"2026-04-27T19:49:49.304187Z","end":"2026-04-27T19:49:50.060235Z","steps":["trace[542978545] 'agreement among raft nodes before linearized reading' (duration: 755.924091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.060279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.304167Z","time spent":"756.106036ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-27T19:49:50.3309Z","caller":"traceutil/trace.go:171","msg":"trace[598555254] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"266.896372ms","start":"2026-04-27T19:49:50.063985Z","end":"2026-04-27T19:49:50.330881Z","steps":["trace[598555254] 'process raft request' (duration: 266.864481ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:50.331136Z","caller":"traceutil/trace.go:171","msg":"trace[1672201346] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"508.259531ms","start":"2026-04-27T19:49:49.822867Z","end":"2026-04-27T19:49:50.331127Z","steps":["trace[1672201346] 'process raft request' (duration: 410.523527ms)","trace[1672201346] 'compare' (duration: 97.366245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:50.331204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.822849Z","time spent":"508.303803ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:50.331355Z","caller":"traceutil/trace.go:171","msg":"trace[1449590755] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1425; }","duration":"271.818417ms","start":"2026-04-27T19:49:50.059527Z","end":"2026-04-27T19:49:50.331345Z","steps":["trace[1449590755] 'read index received' (duration: 173.876187ms)","trace[1449590755] 'applied index is now lower than readState.Index' (duration: 97.94115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:50.331405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.719936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:49:50.331429Z","caller":"traceutil/trace.go:171","msg":"trace[2128170622] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1371; }","duration":"900.784338ms","start":"2026-04-27T19:49:49.430635Z","end":"2026-04-27T19:49:50.331419Z","steps":["trace[2128170622] 'agreement among raft nodes before linearized reading' (duration: 900.744337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.331451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.430611Z","time spent":"900.832709ms","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:49:50.331655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.856775ms","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-27T19:49:50.331674Z","caller":"traceutil/trace.go:171","msg":"trace[1966841524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"323.912367ms","start":"2026-04-27T19:49:50.007756Z","end":"2026-04-27T19:49:50.331668Z","steps":["trace[1966841524] 'agreement among raft nodes before linearized reading' (duration: 323.836275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.331696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:50.007735Z","time spent":"323.956497ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:49:50.331818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.08059ms","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-27T19:49:50.331835Z","caller":"traceutil/trace.go:171","msg":"trace[456669540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"324.122082ms","start":"2026-04-27T19:49:50.007708Z","end":"2026-04-27T19:49:50.33183Z","steps":["trace[456669540] 'agreement among raft nodes before linearized reading' (duration: 324.070721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.331852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:50.007693Z","time spent":"324.155152ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:49:50.33195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.154478ms","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-27T19:49:50.331966Z","caller":"traceutil/trace.go:171","msg":"trace[478225236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1371; }","duration":"345.224299ms","start":"2026-04-27T19:49:49.986737Z","end":"2026-04-27T19:49:50.331961Z","steps":["trace[478225236] 'agreement among raft nodes before linearized reading' (duration: 345.177948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.331983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.986713Z","time spent":"345.266091ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:49:50.332087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.549008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:49:50.332104Z","caller":"traceutil/trace.go:171","msg":"trace[1245217363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1371; }","duration":"365.626811ms","start":"2026-04-27T19:49:49.966471Z","end":"2026-04-27T19:49:50.332098Z","steps":["trace[1245217363] 'agreement among raft nodes before linearized reading' (duration: 365.579969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.332122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.96644Z","time spent":"365.677661ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:49:50.332253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.854457ms","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-27T19:49:50.332277Z","caller":"traceutil/trace.go:171","msg":"trace[1884572000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"365.915459ms","start":"2026-04-27T19:49:49.966355Z","end":"2026-04-27T19:49:50.33227Z","steps":["trace[1884572000] 'agreement among raft nodes before linearized reading' (duration: 365.852367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.332301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.966331Z","time spent":"365.96488ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:49:50.332424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.28665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-27T19:49:50.332449Z","caller":"traceutil/trace.go:171","msg":"trace[1579509849] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1371; }","duration":"564.349091ms","start":"2026-04-27T19:49:49.768091Z","end":"2026-04-27T19:49:50.332441Z","steps":["trace[1579509849] 'agreement among raft nodes before linearized reading' (duration: 564.277069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.332472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:49.768072Z","time spent":"564.395513ms","remote":"127.0.0.1:42610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-27T19:49:50.587973Z","caller":"traceutil/trace.go:171","msg":"trace[845070219] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"250.071492ms","start":"2026-04-27T19:49:50.337879Z","end":"2026-04-27T19:49:50.587951Z","steps":["trace[845070219] 'process raft request' (duration: 249.965319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.836887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.900391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:49:50.837079Z","caller":"traceutil/trace.go:171","msg":"trace[608349913] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"498.872031ms","start":"2026-04-27T19:49:50.338179Z","end":"2026-04-27T19:49:50.837051Z","steps":["trace[608349913] 'process raft request' (duration: 327.713773ms)","trace[608349913] 'compare' (duration: 170.783948ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:50.837136Z","caller":"traceutil/trace.go:171","msg":"trace[195980583] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1427; }","duration":"498.86488ms","start":"2026-04-27T19:49:50.338252Z","end":"2026-04-27T19:49:50.837117Z","steps":["trace[195980583] 'read index received' (duration: 249.590569ms)","trace[195980583] 'applied index is now lower than readState.Index' (duration: 249.272281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:50.837181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:50.338167Z","time spent":"498.957404ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:50.8374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.147107ms","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:529"} {"level":"info","ts":"2026-04-27T19:49:50.837425Z","caller":"traceutil/trace.go:171","msg":"trace[1287587051] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1376; }","duration":"499.195649ms","start":"2026-04-27T19:49:50.338222Z","end":"2026-04-27T19:49:50.837417Z","steps":["trace[1287587051] 'agreement among raft nodes before linearized reading' (duration: 499.113477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.837452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:50.338213Z","time spent":"499.236ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-27T19:49:50.837492Z","caller":"traceutil/trace.go:171","msg":"trace[201793727] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"499.190359ms","start":"2026-04-27T19:49:50.338296Z","end":"2026-04-27T19:49:50.837487Z","steps":["trace[201793727] 'process raft request' (duration: 498.697826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.837523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:50.338291Z","time spent":"499.215269ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:50.837549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.199358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:49:50.837595Z","caller":"traceutil/trace.go:171","msg":"trace[803166960] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1376; }","duration":"457.28546ms","start":"2026-04-27T19:49:50.380298Z","end":"2026-04-27T19:49:50.837583Z","steps":["trace[803166960] 'agreement among raft nodes before linearized reading' (duration: 457.210268ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:50.837581Z","caller":"traceutil/trace.go:171","msg":"trace[1459880916] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"498.537931ms","start":"2026-04-27T19:49:50.339014Z","end":"2026-04-27T19:49:50.837552Z","steps":["trace[1459880916] 'process raft request' (duration: 498.030709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.837632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:50.380276Z","time spent":"457.345611ms","remote":"127.0.0.1:42986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:49:50.837566Z","caller":"traceutil/trace.go:171","msg":"trace[1477440925] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"497.741172ms","start":"2026-04-27T19:49:50.33982Z","end":"2026-04-27T19:49:50.837561Z","steps":["trace[1477440925] 'process raft request' (duration: 497.258159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:50.837713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:50.338968Z","time spent":"498.677847ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:50.837747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:50.33981Z","time spent":"497.918587ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:51.137147Z","caller":"traceutil/trace.go:171","msg":"trace[1464076068] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"265.194757ms","start":"2026-04-27T19:49:50.871918Z","end":"2026-04-27T19:49:51.137112Z","steps":["trace[1464076068] 'process raft request' (duration: 165.207016ms)","trace[1464076068] 'compare' (duration: 99.853148ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:52.794961Z","caller":"traceutil/trace.go:171","msg":"trace[798285556] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"204.062558ms","start":"2026-04-27T19:49:52.590872Z","end":"2026-04-27T19:49:52.794934Z","steps":["trace[798285556] 'read index received' (duration: 203.817812ms)","trace[798285556] 'applied index is now lower than readState.Index' (duration: 243.516µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:52.795436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.55151ms","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-27T19:49:52.795488Z","caller":"traceutil/trace.go:171","msg":"trace[1848657556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1380; }","duration":"204.663162ms","start":"2026-04-27T19:49:52.590811Z","end":"2026-04-27T19:49:52.795474Z","steps":["trace[1848657556] 'agreement among raft nodes before linearized reading' (duration: 204.487168ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:52.795496Z","caller":"traceutil/trace.go:171","msg":"trace[1603710285] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"456.5095ms","start":"2026-04-27T19:49:52.338861Z","end":"2026-04-27T19:49:52.79537Z","steps":["trace[1603710285] 'process raft request' (duration: 455.914015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:52.795688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:52.338836Z","time spent":"456.748426ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:53.300256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.129526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"warn","ts":"2026-04-27T19:49:53.300316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.132447ms","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-27T19:49:53.300365Z","caller":"traceutil/trace.go:171","msg":"trace[1760371167] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1381; }","duration":"158.267979ms","start":"2026-04-27T19:49:53.142064Z","end":"2026-04-27T19:49:53.300332Z","steps":["trace[1760371167] 'range keys from in-memory index tree' (duration: 157.924141ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:53.300421Z","caller":"traceutil/trace.go:171","msg":"trace[1765207970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1381; }","duration":"459.290781ms","start":"2026-04-27T19:49:52.841103Z","end":"2026-04-27T19:49:53.300393Z","steps":["trace[1765207970] 'range keys from in-memory index tree' (duration: 458.950142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:53.300625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:52.841083Z","time spent":"459.522597ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:49:53.300766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.723497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T19:49:53.300902Z","caller":"traceutil/trace.go:171","msg":"trace[1890497476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"458.878251ms","start":"2026-04-27T19:49:52.842008Z","end":"2026-04-27T19:49:53.300886Z","steps":["trace[1890497476] 'range keys from in-memory index tree' (duration: 458.576663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:53.300917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.903792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-27T19:49:53.300979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:52.841995Z","time spent":"458.969423ms","remote":"127.0.0.1:42784","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-27T19:49:53.301035Z","caller":"traceutil/trace.go:171","msg":"trace[1937764776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1381; }","duration":"459.062835ms","start":"2026-04-27T19:49:52.841947Z","end":"2026-04-27T19:49:53.301009Z","steps":["trace[1937764776] 'range keys from in-memory index tree' (duration: 458.709287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:53.301135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:52.841936Z","time spent":"459.148778ms","remote":"127.0.0.1:42784","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-27T19:49:53.300589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.185548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T19:49:53.304127Z","caller":"traceutil/trace.go:171","msg":"trace[81513279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1381; }","duration":"461.435895ms","start":"2026-04-27T19:49:52.841347Z","end":"2026-04-27T19:49:53.302783Z","steps":["trace[81513279] 'range keys from in-memory index tree' (duration: 458.923842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:53.304212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:52.841329Z","time spent":"462.863162ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:49:54.647492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.5617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:49:54.647747Z","caller":"traceutil/trace.go:171","msg":"trace[27339748] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1439; }","duration":"1.064602649s","start":"2026-04-27T19:49:53.583128Z","end":"2026-04-27T19:49:54.64773Z","steps":["trace[27339748] 'read index received' (duration: 281.7515ms)","trace[27339748] 'applied index is now lower than readState.Index' (duration: 782.850169ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:54.648047Z","caller":"traceutil/trace.go:171","msg":"trace[1460718681] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"1.341260849s","start":"2026-04-27T19:49:53.306755Z","end":"2026-04-27T19:49:54.648015Z","steps":["trace[1460718681] 'process raft request' (duration: 558.097702ms)","trace[1460718681] 'compare' (duration: 782.465558ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:54.648094Z","caller":"traceutil/trace.go:171","msg":"trace[311993448] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"1.34006781s","start":"2026-04-27T19:49:53.308013Z","end":"2026-04-27T19:49:54.64808Z","steps":["trace[311993448] 'process raft request' (duration: 1.339572037s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:54.648116Z","caller":"traceutil/trace.go:171","msg":"trace[1274238195] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"1.339769341s","start":"2026-04-27T19:49:53.308308Z","end":"2026-04-27T19:49:54.648077Z","steps":["trace[1274238195] 'process raft request' (duration: 1.339335561s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:54.648166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:53.307991Z","time spent":"1.340137201s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:54.648161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.371855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-04-27T19:49:54.648199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:53.306747Z","time spent":"1.341372833s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:54.648222Z","caller":"traceutil/trace.go:171","msg":"trace[42233731] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1386; }","duration":"345.488848ms","start":"2026-04-27T19:49:54.302714Z","end":"2026-04-27T19:49:54.648203Z","steps":["trace[42233731] 'agreement among raft nodes before linearized reading' (duration: 345.260032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:54.648233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:53.308282Z","time spent":"1.339895525s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:49:54.648267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:54.302683Z","time spent":"345.57202ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-27T19:49:54.64836Z","caller":"traceutil/trace.go:171","msg":"trace[747196627] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"1.334014353s","start":"2026-04-27T19:49:53.314333Z","end":"2026-04-27T19:49:54.648347Z","steps":["trace[747196627] 'process raft request' (duration: 1.333357757s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:54.648395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065278126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:49:54.648415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:53.314312Z","time spent":"1.334072805s","remote":"127.0.0.1:42610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:49:54.648438Z","caller":"traceutil/trace.go:171","msg":"trace[1067231593] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1386; }","duration":"1.065319487s","start":"2026-04-27T19:49:53.583102Z","end":"2026-04-27T19:49:54.648421Z","steps":["trace[1067231593] 'agreement among raft nodes before linearized reading' (duration: 1.065256196s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:54.648478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:53.583011Z","time spent":"1.0654541s","remote":"127.0.0.1:46628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:49:58.784427Z","caller":"traceutil/trace.go:171","msg":"trace[254923705] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"119.231862ms","start":"2026-04-27T19:49:58.665162Z","end":"2026-04-27T19:49:58.784394Z","steps":["trace[254923705] 'process raft request' (duration: 119.068058ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:58.844829Z","caller":"traceutil/trace.go:171","msg":"trace[1157129222] linearizableReadLoop","detail":"{readStateIndex:1465; appliedIndex:1463; }","duration":"140.795694ms","start":"2026-04-27T19:49:58.704009Z","end":"2026-04-27T19:49:58.844805Z","steps":["trace[1157129222] 'read index received' (duration: 80.218547ms)","trace[1157129222] 'applied index is now lower than readState.Index' (duration: 60.576077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:58.845322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.293856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-27T19:49:58.844844Z","caller":"traceutil/trace.go:171","msg":"trace[2085992115] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"142.778554ms","start":"2026-04-27T19:49:58.702014Z","end":"2026-04-27T19:49:58.844793Z","steps":["trace[2085992115] 'process raft request' (duration: 117.215602ms)","trace[2085992115] 'compare' (duration: 25.393708ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:58.845424Z","caller":"traceutil/trace.go:171","msg":"trace[663853505] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1408; }","duration":"141.418628ms","start":"2026-04-27T19:49:58.703976Z","end":"2026-04-27T19:49:58.845394Z","steps":["trace[663853505] 'agreement among raft nodes before linearized reading' (duration: 140.960377ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:59.010556Z","caller":"traceutil/trace.go:171","msg":"trace[1791092385] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"158.398652ms","start":"2026-04-27T19:49:58.852121Z","end":"2026-04-27T19:49:59.01052Z","steps":["trace[1791092385] 'process raft request' (duration: 96.276997ms)","trace[1791092385] 'compare' (duration: 61.974192ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:59.142242Z","caller":"traceutil/trace.go:171","msg":"trace[1790234780] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"122.189909ms","start":"2026-04-27T19:49:59.020022Z","end":"2026-04-27T19:49:59.142212Z","steps":["trace[1790234780] 'process raft request' (duration: 122.035975ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:53:09.433Z","caller":"traceutil/trace.go:171","msg":"trace[1830465171] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"365.826405ms","start":"2026-04-27T19:53:09.067149Z","end":"2026-04-27T19:53:09.432975Z","steps":["trace[1830465171] 'process raft request' (duration: 365.708492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:53:09.43312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:53:09.067127Z","time spent":"365.930748ms","remote":"127.0.0.1:42610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:53:18.082217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.510694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:53:18.082373Z","caller":"traceutil/trace.go:171","msg":"trace[1822031380] linearizableReadLoop","detail":"{readStateIndex:2552; appliedIndex:2551; }","duration":"125.81081ms","start":"2026-04-27T19:53:17.956547Z","end":"2026-04-27T19:53:18.082358Z","steps":["trace[1822031380] 'read index received' (duration: 58.532µs)","trace[1822031380] 'applied index is now lower than readState.Index' (duration: 125.750938ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:53:18.082394Z","caller":"traceutil/trace.go:171","msg":"trace[1796855995] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"219.061574ms","start":"2026-04-27T19:53:17.863308Z","end":"2026-04-27T19:53:18.082369Z","steps":["trace[1796855995] 'process raft request' (duration: 31.339304ms)","trace[1796855995] 'compare' (duration: 187.403342ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:53:18.08252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.977024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:53:18.08256Z","caller":"traceutil/trace.go:171","msg":"trace[1281258290] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2453; }","duration":"126.081137ms","start":"2026-04-27T19:53:17.956471Z","end":"2026-04-27T19:53:18.082553Z","steps":["trace[1281258290] 'agreement among raft nodes before linearized reading' (duration: 125.998495ms)"],"step_count":1}