{"level":"warn","ts":"2026-04-07T12:51:19.780371Z","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-07T12:51:19.780468Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T12:51:19.78054Z","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-07T12:51:19.78055Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-07T12:51:19.780574Z","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-07T12:51:19.781205Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-07T12:51:19.781278Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T12:51:19.786881Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.337457ms"} {"level":"info","ts":"2026-04-07T12:51:19.79416Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-07T12:51:19.794228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:51:19.794254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-07T12:51:19.794273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:51:19.794281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-07T12:51:19.79432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-07T12:51:19.805265Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:51:19.809942Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:51:19.811417Z","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-07T12:51:19.816078Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:51:19.817232Z","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-07T12:51:19.817214Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:51:19.817373Z","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-07T12:51:19.817371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-07T12:51:19.817386Z","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-07T12:51:19.819652Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-07T12:51:19.822416Z","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-07T12:51:19.822557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-07T12:51:19.822591Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-07T12:51:19.822722Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:51:19.822767Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:51:19.995137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:51:19.995211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:51:19.995234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-07T12:51:19.995251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:51:19.995266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-07T12:51:19.99528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-07T12:51:19.995297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-07T12:51:19.996593Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:51:19.997397Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:51:19.997651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:51:19.997707Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:51:19.998437Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:51:19.998369Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:51:19.998529Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:51:19.9987Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:51:19.998764Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:51:20.000384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:51:20.000944Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-04-07T12:51:27.19245Z","caller":"traceutil/trace.go:171","msg":"trace[60246276] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"274.438409ms","start":"2026-04-07T12:51:26.91798Z","end":"2026-04-07T12:51:27.192418Z","steps":["trace[60246276] 'process raft request' (duration: 274.289127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:28.39153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.814459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:51:28.391671Z","caller":"traceutil/trace.go:171","msg":"trace[1202574254] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"771.998993ms","start":"2026-04-07T12:51:27.619657Z","end":"2026-04-07T12:51:28.391656Z","steps":["trace[1202574254] 'read index received' (duration: 588.798µs)","trace[1202574254] 'applied index is now lower than readState.Index' (duration: 771.409135ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:28.39174Z","caller":"traceutil/trace.go:171","msg":"trace[1257150181] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"1.188145597s","start":"2026-04-07T12:51:27.203588Z","end":"2026-04-07T12:51:28.391733Z","steps":["trace[1257150181] 'process raft request' (duration: 416.726561ms)","trace[1257150181] 'compare' (duration: 770.658977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:28.391783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:27.203571Z","time spent":"1.188192068s","remote":"127.0.0.1:55646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:51:28.392064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.387617ms","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-07T12:51:28.392148Z","caller":"traceutil/trace.go:171","msg":"trace[133046618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:286; }","duration":"772.55126ms","start":"2026-04-07T12:51:27.619578Z","end":"2026-04-07T12:51:28.392129Z","steps":["trace[133046618] 'agreement among raft nodes before linearized reading' (duration: 772.333917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:28.392157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.075541ms","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-07T12:51:28.392189Z","caller":"traceutil/trace.go:171","msg":"trace[2090911139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:286; }","duration":"626.134641ms","start":"2026-04-07T12:51:27.766047Z","end":"2026-04-07T12:51:28.392182Z","steps":["trace[2090911139] 'agreement among raft nodes before linearized reading' (duration: 626.071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:28.392198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:27.619542Z","time spent":"772.6467ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T12:51:28.392211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:27.766037Z","time spent":"626.169872ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T12:51:28.392712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.541278ms","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-07T12:51:28.39276Z","caller":"traceutil/trace.go:171","msg":"trace[704591286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:286; }","duration":"627.589588ms","start":"2026-04-07T12:51:27.765158Z","end":"2026-04-07T12:51:28.392748Z","steps":["trace[704591286] 'agreement among raft nodes before linearized reading' (duration: 626.653468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:28.39279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:27.765104Z","time spent":"627.67899ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T12:51:28.673052Z","caller":"traceutil/trace.go:171","msg":"trace[1709276160] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"276.338728ms","start":"2026-04-07T12:51:28.396678Z","end":"2026-04-07T12:51:28.673017Z","steps":["trace[1709276160] 'process raft request' (duration: 252.159719ms)","trace[1709276160] 'compare' (duration: 23.913565ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:28.673054Z","caller":"traceutil/trace.go:171","msg":"trace[777743773] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"275.433197ms","start":"2026-04-07T12:51:28.397589Z","end":"2026-04-07T12:51:28.673022Z","steps":["trace[777743773] 'process raft request' (duration: 275.322596ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:28.673204Z","caller":"traceutil/trace.go:171","msg":"trace[1924200871] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:299; }","duration":"274.870581ms","start":"2026-04-07T12:51:28.398308Z","end":"2026-04-07T12:51:28.673178Z","steps":["trace[1924200871] 'read index received' (duration: 250.673082ms)","trace[1924200871] 'applied index is now lower than readState.Index' (duration: 24.196239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:28.673329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.005702ms","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-07T12:51:28.6733Z","caller":"traceutil/trace.go:171","msg":"trace[1256643302] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"275.606349ms","start":"2026-04-07T12:51:28.397647Z","end":"2026-04-07T12:51:28.673253Z","steps":["trace[1256643302] 'process raft request' (duration: 275.328526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:28.673364Z","caller":"traceutil/trace.go:171","msg":"trace[79669282] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"275.071523ms","start":"2026-04-07T12:51:28.398282Z","end":"2026-04-07T12:51:28.673354Z","steps":["trace[79669282] 'agreement among raft nodes before linearized reading' (duration: 274.987162ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.51373Z","caller":"traceutil/trace.go:171","msg":"trace[2016908450] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"248.188572ms","start":"2026-04-07T12:51:50.265511Z","end":"2026-04-07T12:51:50.5137Z","steps":["trace[2016908450] 'process raft request' (duration: 242.135351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.51453Z","caller":"traceutil/trace.go:171","msg":"trace[1184111963] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"244.913259ms","start":"2026-04-07T12:51:50.269518Z","end":"2026-04-07T12:51:50.514431Z","steps":["trace[1184111963] 'process raft request' (duration: 244.714037ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.514721Z","caller":"traceutil/trace.go:171","msg":"trace[258155054] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"237.644615ms","start":"2026-04-07T12:51:50.277031Z","end":"2026-04-07T12:51:50.514676Z","steps":["trace[258155054] 'process raft request' (duration: 237.333712ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.514936Z","caller":"traceutil/trace.go:171","msg":"trace[243488963] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"143.528402ms","start":"2026-04-07T12:51:50.371392Z","end":"2026-04-07T12:51:50.514921Z","steps":["trace[243488963] 'process raft request' (duration: 143.34753ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.51494Z","caller":"traceutil/trace.go:171","msg":"trace[151235099] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"235.441045ms","start":"2026-04-07T12:51:50.27947Z","end":"2026-04-07T12:51:50.514911Z","steps":["trace[151235099] 'process raft request' (duration: 235.10928ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.749785Z","caller":"traceutil/trace.go:171","msg":"trace[819706983] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"201.899787ms","start":"2026-04-07T12:51:50.547856Z","end":"2026-04-07T12:51:50.749756Z","steps":["trace[819706983] 'process raft request' (duration: 193.089629ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.751913Z","caller":"traceutil/trace.go:171","msg":"trace[637800621] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"198.560104ms","start":"2026-04-07T12:51:50.553331Z","end":"2026-04-07T12:51:50.751891Z","steps":["trace[637800621] 'process raft request' (duration: 198.409242ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.752378Z","caller":"traceutil/trace.go:171","msg":"trace[1433584779] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"187.340441ms","start":"2026-04-07T12:51:50.565006Z","end":"2026-04-07T12:51:50.752346Z","steps":["trace[1433584779] 'process raft request' (duration: 186.854006ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:50.752405Z","caller":"traceutil/trace.go:171","msg":"trace[511938971] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"148.38484ms","start":"2026-04-07T12:51:50.604012Z","end":"2026-04-07T12:51:50.752397Z","steps":["trace[511938971] 'process raft request' (duration: 148.233499ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:51:57.288677Z","caller":"traceutil/trace.go:171","msg":"trace[1530253438] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"452.948744ms","start":"2026-04-07T12:51:56.835699Z","end":"2026-04-07T12:51:57.288647Z","steps":["trace[1530253438] 'read index received' (duration: 452.097825ms)","trace[1530253438] 'applied index is now lower than readState.Index' (duration: 849.969µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:57.288771Z","caller":"traceutil/trace.go:171","msg":"trace[2025187331] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"526.641156ms","start":"2026-04-07T12:51:56.762038Z","end":"2026-04-07T12:51:57.288679Z","steps":["trace[2025187331] 'process raft request' (duration: 526.000779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:57.289665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:56.762015Z","time spent":"527.135251ms","remote":"127.0.0.1:55556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:51:57.800191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.349064ms","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-07T12:51:57.800273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.597106ms","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-07T12:51:57.800367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.666225ms","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-07T12:51:57.800427Z","caller":"traceutil/trace.go:171","msg":"trace[889626205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"964.781356ms","start":"2026-04-07T12:51:56.835624Z","end":"2026-04-07T12:51:57.800405Z","steps":["trace[889626205] 'agreement among raft nodes before linearized reading' (duration: 453.167416ms)","trace[889626205] 'range keys from in-memory index tree' (duration: 511.453439ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:57.800435Z","caller":"traceutil/trace.go:171","msg":"trace[1927501726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"959.822327ms","start":"2026-04-07T12:51:56.840588Z","end":"2026-04-07T12:51:57.800411Z","steps":["trace[1927501726] 'agreement among raft nodes before linearized reading' (duration: 448.227487ms)","trace[1927501726] 'range keys from in-memory index tree' (duration: 511.111276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:57.800482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:56.835602Z","time spent":"964.869517ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T12:51:57.800372Z","caller":"traceutil/trace.go:171","msg":"trace[2074832719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"958.647457ms","start":"2026-04-07T12:51:56.841701Z","end":"2026-04-07T12:51:57.800348Z","steps":["trace[2074832719] 'agreement among raft nodes before linearized reading' (duration: 447.172598ms)","trace[2074832719] 'range keys from in-memory index tree' (duration: 511.157156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:57.800517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:56.840567Z","time spent":"959.935788ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T12:51:57.800624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:56.841684Z","time spent":"958.914319ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T12:51:57.800502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.737921ms","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-07T12:51:57.800799Z","caller":"traceutil/trace.go:171","msg":"trace[965772096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"795.077605ms","start":"2026-04-07T12:51:57.005684Z","end":"2026-04-07T12:51:57.800762Z","steps":["trace[965772096] 'agreement among raft nodes before linearized reading' (duration: 283.179333ms)","trace[965772096] 'range keys from in-memory index tree' (duration: 511.466349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:57.800875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.005665Z","time spent":"795.169966ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:51:57.801143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.417444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-txf59\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T12:51:57.801189Z","caller":"traceutil/trace.go:171","msg":"trace[1276608576] range","detail":"{range_begin:/registry/pods/kube-system/cilium-txf59; range_end:; response_count:1; response_revision:577; }","duration":"492.493694ms","start":"2026-04-07T12:51:57.308683Z","end":"2026-04-07T12:51:57.801177Z","steps":["trace[1276608576] 'range keys from in-memory index tree' (duration: 492.269002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:57.801249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.308667Z","time spent":"492.544855ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-txf59\" "} {"level":"info","ts":"2026-04-07T12:51:57.856442Z","caller":"traceutil/trace.go:171","msg":"trace[846761121] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"392.5952ms","start":"2026-04-07T12:51:57.463823Z","end":"2026-04-07T12:51:57.856418Z","steps":["trace[846761121] 'read index received' (duration: 392.499989ms)","trace[846761121] 'applied index is now lower than readState.Index' (duration: 94.201µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:57.856599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.754531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:51:57.856632Z","caller":"traceutil/trace.go:171","msg":"trace[84828345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"392.838932ms","start":"2026-04-07T12:51:57.463784Z","end":"2026-04-07T12:51:57.856623Z","steps":["trace[84828345] 'agreement among raft nodes before linearized reading' (duration: 392.728681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:57.856667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.463765Z","time spent":"392.893782ms","remote":"127.0.0.1:55470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T12:51:57.856808Z","caller":"traceutil/trace.go:171","msg":"trace[1184136037] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"495.025338ms","start":"2026-04-07T12:51:57.361691Z","end":"2026-04-07T12:51:57.856717Z","steps":["trace[1184136037] 'process raft request' (duration: 494.552044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:57.857541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.361668Z","time spent":"495.389133ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:51:58.371566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743854545565404,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T12:51:58.863156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.006883114s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T12:51:59.09894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.10418ms","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-07T12:51:59.099217Z","caller":"traceutil/trace.go:171","msg":"trace[6906911] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:578; }","duration":"727.395059ms","start":"2026-04-07T12:51:58.371802Z","end":"2026-04-07T12:51:59.099197Z","steps":["trace[6906911] 'range keys from in-memory index tree' (duration: 727.0882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:59.100341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.698757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:51:59.100713Z","caller":"traceutil/trace.go:171","msg":"trace[1046527336] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.295156486s","start":"2026-04-07T12:51:57.805511Z","end":"2026-04-07T12:51:59.100667Z","steps":["trace[1046527336] 'process raft request' (duration: 1.058034033s)","trace[1046527336] 'compare' (duration: 235.547772ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:51:59.10101Z","caller":"traceutil/trace.go:171","msg":"trace[287427948] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.294936722s","start":"2026-04-07T12:51:57.806047Z","end":"2026-04-07T12:51:59.100983Z","steps":["trace[287427948] 'process raft request' (duration: 1.294619882s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:59.10104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.805492Z","time spent":"1.295330532s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:51:59.101179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.806039Z","time spent":"1.295067796s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:51:59.100754Z","caller":"traceutil/trace.go:171","msg":"trace[796112837] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.295040467s","start":"2026-04-07T12:51:57.805684Z","end":"2026-04-07T12:51:59.100724Z","steps":["trace[796112837] 'process raft request' (duration: 1.29483269s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:59.101422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.805674Z","time spent":"1.295678257s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:51:59.101522Z","caller":"traceutil/trace.go:171","msg":"trace[1883012964] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.288691851s","start":"2026-04-07T12:51:57.812744Z","end":"2026-04-07T12:51:59.101436Z","steps":["trace[1883012964] 'process raft request' (duration: 1.288071211s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:59.101717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.812733Z","time spent":"1.288901077s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:51:59.101549Z","caller":"traceutil/trace.go:171","msg":"trace[731787237] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.285114348s","start":"2026-04-07T12:51:57.816423Z","end":"2026-04-07T12:51:59.101537Z","steps":["trace[731787237] 'process raft request' (duration: 1.284489058s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:59.101957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.816388Z","time spent":"1.285524711s","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:51:59.101593Z","caller":"traceutil/trace.go:171","msg":"trace[172472019] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:599; }","duration":"1.230099573s","start":"2026-04-07T12:51:57.871483Z","end":"2026-04-07T12:51:59.101583Z","steps":["trace[172472019] 'read index received' (duration: 991.965378ms)","trace[172472019] 'applied index is now lower than readState.Index' (duration: 238.132755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:51:59.109826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.730998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:51:59.109877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238348862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:51:59.109929Z","caller":"traceutil/trace.go:171","msg":"trace[172530662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"645.883693ms","start":"2026-04-07T12:51:58.46402Z","end":"2026-04-07T12:51:59.109903Z","steps":["trace[172530662] 'agreement among raft nodes before linearized reading' (duration: 645.703547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:59.109979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:58.464Z","time spent":"645.969505ms","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T12:51:59.11004Z","caller":"traceutil/trace.go:171","msg":"trace[1816478619] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:583; }","duration":"1.238490986s","start":"2026-04-07T12:51:57.871454Z","end":"2026-04-07T12:51:59.109945Z","steps":["trace[1816478619] 'agreement among raft nodes before linearized reading' (duration: 1.230159764s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:51:59.110147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:51:57.871435Z","time spent":"1.238695144s","remote":"127.0.0.1:55680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-07T12:52:00.803987Z","caller":"traceutil/trace.go:171","msg":"trace[907226623] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"342.613805ms","start":"2026-04-07T12:52:00.461348Z","end":"2026-04-07T12:52:00.803962Z","steps":["trace[907226623] 'read index received' (duration: 331.488825ms)","trace[907226623] 'applied index is now lower than readState.Index' (duration: 11.1235ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:52:00.80411Z","caller":"traceutil/trace.go:171","msg":"trace[1424998557] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"347.077659ms","start":"2026-04-07T12:52:00.457022Z","end":"2026-04-07T12:52:00.804099Z","steps":["trace[1424998557] 'process raft request' (duration: 335.900007ms)","trace[1424998557] 'compare' (duration: 10.909194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:52:00.804188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.829203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:52:00.804228Z","caller":"traceutil/trace.go:171","msg":"trace[711238724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"342.916385ms","start":"2026-04-07T12:52:00.461303Z","end":"2026-04-07T12:52:00.80422Z","steps":["trace[711238724] 'agreement among raft nodes before linearized reading' (duration: 342.836633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:00.804218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:00.456996Z","time spent":"347.144123ms","remote":"127.0.0.1:55500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:52:00.804251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:00.461278Z","time spent":"342.967917ms","remote":"127.0.0.1:55470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:52:00.804601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.449427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:52:00.804759Z","caller":"traceutil/trace.go:171","msg":"trace[1248843604] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:616; }","duration":"194.678164ms","start":"2026-04-07T12:52:00.610053Z","end":"2026-04-07T12:52:00.804731Z","steps":["trace[1248843604] 'agreement among raft nodes before linearized reading' (duration: 194.105255ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:08.204199Z","caller":"traceutil/trace.go:171","msg":"trace[1135389143] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"109.356299ms","start":"2026-04-07T12:52:08.094811Z","end":"2026-04-07T12:52:08.204167Z","steps":["trace[1135389143] 'process raft request' (duration: 109.183933ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:43.952247Z","caller":"traceutil/trace.go:171","msg":"trace[603061267] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"197.763571ms","start":"2026-04-07T12:52:43.754395Z","end":"2026-04-07T12:52:43.952159Z","steps":["trace[603061267] 'process raft request' (duration: 195.269296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:44.840485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.265657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:52:44.840618Z","caller":"traceutil/trace.go:171","msg":"trace[1491879560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"376.460011ms","start":"2026-04-07T12:52:44.464134Z","end":"2026-04-07T12:52:44.840594Z","steps":["trace[1491879560] 'range keys from in-memory index tree' (duration: 376.140704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:44.840698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:44.464102Z","time spent":"376.564363ms","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:52:44.840778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.115984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:52:44.840836Z","caller":"traceutil/trace.go:171","msg":"trace[449646874] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1148; }","duration":"218.217536ms","start":"2026-04-07T12:52:44.622602Z","end":"2026-04-07T12:52:44.840819Z","steps":["trace[449646874] 'count revisions from in-memory index tree' (duration: 218.010542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:44.840899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.20968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T12:52:44.840925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.037177ms","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-07T12:52:44.840972Z","caller":"traceutil/trace.go:171","msg":"trace[711219043] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1148; }","duration":"422.311323ms","start":"2026-04-07T12:52:44.418643Z","end":"2026-04-07T12:52:44.840955Z","steps":["trace[711219043] 'count revisions from in-memory index tree' (duration: 422.092237ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:44.841017Z","caller":"traceutil/trace.go:171","msg":"trace[1232927934] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1148; }","duration":"668.176991ms","start":"2026-04-07T12:52:44.172819Z","end":"2026-04-07T12:52:44.840996Z","steps":["trace[1232927934] 'count revisions from in-memory index tree' (duration: 667.944845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:44.841027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:44.418628Z","time spent":"422.379074ms","remote":"127.0.0.1:55956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":31,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:52:44.841123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:44.1728Z","time spent":"668.260382ms","remote":"127.0.0.1:55646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":50,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-07T12:52:44.979093Z","caller":"traceutil/trace.go:171","msg":"trace[1605866529] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"134.552738ms","start":"2026-04-07T12:52:44.844521Z","end":"2026-04-07T12:52:44.979074Z","steps":["trace[1605866529] 'process raft request' (duration: 134.422395ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:45.73079Z","caller":"traceutil/trace.go:171","msg":"trace[1383930304] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"150.282967ms","start":"2026-04-07T12:52:45.580486Z","end":"2026-04-07T12:52:45.730769Z","steps":["trace[1383930304] 'process raft request' (duration: 146.882563ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:45.737463Z","caller":"traceutil/trace.go:171","msg":"trace[1921971864] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"108.198665ms","start":"2026-04-07T12:52:45.62924Z","end":"2026-04-07T12:52:45.737439Z","steps":["trace[1921971864] 'process raft request' (duration: 108.073402ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:59.044662Z","caller":"traceutil/trace.go:171","msg":"trace[754271218] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"197.71883ms","start":"2026-04-07T12:52:58.846918Z","end":"2026-04-07T12:52:59.044637Z","steps":["trace[754271218] 'process raft request' (duration: 197.610987ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:59.189594Z","caller":"traceutil/trace.go:171","msg":"trace[434789214] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"100.672025ms","start":"2026-04-07T12:52:59.088887Z","end":"2026-04-07T12:52:59.189559Z","steps":["trace[434789214] 'process raft request' (duration: 89.078058ms)","trace[434789214] 'compare' (duration: 11.377653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:52:59.693579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.851431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:52:59.693677Z","caller":"traceutil/trace.go:171","msg":"trace[120798616] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"229.95932ms","start":"2026-04-07T12:52:59.463701Z","end":"2026-04-07T12:52:59.693661Z","steps":["trace[120798616] 'read index received' (duration: 41.401µs)","trace[120798616] 'applied index is now lower than readState.Index' (duration: 229.916769ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:52:59.693768Z","caller":"traceutil/trace.go:171","msg":"trace[1258951896] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"306.886469ms","start":"2026-04-07T12:52:59.386818Z","end":"2026-04-07T12:52:59.693704Z","steps":["trace[1258951896] 'process raft request' (duration: 57.846065ms)","trace[1258951896] 'compare' (duration: 248.597935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:52:59.693868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.676535ms","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-07T12:52:59.694277Z","caller":"traceutil/trace.go:171","msg":"trace[1736434707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"177.122734ms","start":"2026-04-07T12:52:59.517127Z","end":"2026-04-07T12:52:59.69425Z","steps":["trace[1736434707] 'agreement among raft nodes before linearized reading' (duration: 176.618814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:59.693873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.157753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:52:59.694416Z","caller":"traceutil/trace.go:171","msg":"trace[913634030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"230.752696ms","start":"2026-04-07T12:52:59.463645Z","end":"2026-04-07T12:52:59.694398Z","steps":["trace[913634030] 'agreement among raft nodes before linearized reading' (duration: 230.091432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:59.694042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.350435ms","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-07T12:52:59.694183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:59.386798Z","time spent":"307.345337ms","remote":"127.0.0.1:55770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1128,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:52:59.694605Z","caller":"traceutil/trace.go:171","msg":"trace[1910699111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"131.937717ms","start":"2026-04-07T12:52:59.562624Z","end":"2026-04-07T12:52:59.694562Z","steps":["trace[1910699111] 'agreement among raft nodes before linearized reading' (duration: 131.246913ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:02.882381Z","caller":"traceutil/trace.go:171","msg":"trace[347483007] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"259.556482ms","start":"2026-04-07T12:53:02.622801Z","end":"2026-04-07T12:53:02.882358Z","steps":["trace[347483007] 'process raft request' (duration: 259.432569ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:10.093863Z","caller":"traceutil/trace.go:171","msg":"trace[1864768515] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1460; }","duration":"259.966104ms","start":"2026-04-07T12:53:09.833865Z","end":"2026-04-07T12:53:10.093831Z","steps":["trace[1864768515] 'read index received' (duration: 259.794511ms)","trace[1864768515] 'applied index is now lower than readState.Index' (duration: 170.733µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:10.093943Z","caller":"traceutil/trace.go:171","msg":"trace[1353665894] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"260.369381ms","start":"2026-04-07T12:53:09.833539Z","end":"2026-04-07T12:53:10.093909Z","steps":["trace[1353665894] 'process raft request' (duration: 260.109847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:10.094132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.235899ms","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-07T12:53:10.094197Z","caller":"traceutil/trace.go:171","msg":"trace[2111963024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1417; }","duration":"260.34171ms","start":"2026-04-07T12:53:09.833839Z","end":"2026-04-07T12:53:10.094181Z","steps":["trace[2111963024] 'agreement among raft nodes before linearized reading' (duration: 260.136327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:10.53334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.814772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:10.533519Z","caller":"traceutil/trace.go:171","msg":"trace[1082190727] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"439.549099ms","start":"2026-04-07T12:53:10.093953Z","end":"2026-04-07T12:53:10.533503Z","steps":["trace[1082190727] 'read index received' (duration: 91.457942ms)","trace[1082190727] 'applied index is now lower than readState.Index' (duration: 348.089787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:10.53386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.418143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:1 size:2122"} {"level":"warn","ts":"2026-04-07T12:53:10.533874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.912559ms","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-07T12:53:10.533845Z","caller":"traceutil/trace.go:171","msg":"trace[158340674] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"434.348123ms","start":"2026-04-07T12:53:10.099451Z","end":"2026-04-07T12:53:10.5338Z","steps":["trace[158340674] 'process raft request' (duration: 434.010737ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:10.533935Z","caller":"traceutil/trace.go:171","msg":"trace[1543576216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1419; }","duration":"700.021571ms","start":"2026-04-07T12:53:09.833898Z","end":"2026-04-07T12:53:10.533919Z","steps":["trace[1543576216] 'agreement among raft nodes before linearized reading' (duration: 699.704346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:10.533959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.450998ms","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-07T12:53:10.533985Z","caller":"traceutil/trace.go:171","msg":"trace[2134439881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1419; }","duration":"688.505878ms","start":"2026-04-07T12:53:09.845471Z","end":"2026-04-07T12:53:10.533977Z","steps":["trace[2134439881] 'agreement among raft nodes before linearized reading' (duration: 688.06887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:10.533982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:09.833875Z","time spent":"700.093883ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T12:53:10.534002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:09.845447Z","time spent":"688.55102ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T12:53:10.533902Z","caller":"traceutil/trace.go:171","msg":"trace[743638727] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:1; response_revision:1419; }","duration":"195.517145ms","start":"2026-04-07T12:53:10.338373Z","end":"2026-04-07T12:53:10.53389Z","steps":["trace[743638727] 'agreement among raft nodes before linearized reading' (duration: 195.339022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:10.53415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:10.099418Z","time spent":"434.527657ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:10.533669Z","caller":"traceutil/trace.go:171","msg":"trace[719555156] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"673.602025ms","start":"2026-04-07T12:53:09.85987Z","end":"2026-04-07T12:53:10.533472Z","steps":["trace[719555156] 'process raft request' (duration: 325.526859ms)","trace[719555156] 'compare' (duration: 347.668859ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:10.534324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:09.859845Z","time spent":"674.42871ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:53:10.881407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.896114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:10.881682Z","caller":"traceutil/trace.go:171","msg":"trace[939860631] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"341.471355ms","start":"2026-04-07T12:53:10.54016Z","end":"2026-04-07T12:53:10.881632Z","steps":["trace[939860631] 'process raft request' (duration: 157.212725ms)","trace[939860631] 'compare' (duration: 183.409714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:10.881819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:10.540141Z","time spent":"341.616737ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:10.882133Z","caller":"traceutil/trace.go:171","msg":"trace[1639090975] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"341.366174ms","start":"2026-04-07T12:53:10.540747Z","end":"2026-04-07T12:53:10.882113Z","steps":["trace[1639090975] 'process raft request' (duration: 340.809534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:10.882362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:10.540715Z","time spent":"341.472335ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:23.496452Z","caller":"traceutil/trace.go:171","msg":"trace[1064548890] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"104.119639ms","start":"2026-04-07T12:53:23.392306Z","end":"2026-04-07T12:53:23.496426Z","steps":["trace[1064548890] 'process raft request' (duration: 103.894875ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:29.920357Z","caller":"traceutil/trace.go:171","msg":"trace[1981863955] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"140.190361ms","start":"2026-04-07T12:53:29.780135Z","end":"2026-04-07T12:53:29.920325Z","steps":["trace[1981863955] 'process raft request' (duration: 139.943266ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:35.063527Z","caller":"traceutil/trace.go:171","msg":"trace[888979360] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"133.187537ms","start":"2026-04-07T12:53:34.930316Z","end":"2026-04-07T12:53:35.063504Z","steps":["trace[888979360] 'process raft request' (duration: 133.049505ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:36.042265Z","caller":"traceutil/trace.go:171","msg":"trace[1168741103] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"118.81779ms","start":"2026-04-07T12:53:35.92341Z","end":"2026-04-07T12:53:36.042227Z","steps":["trace[1168741103] 'process raft request' (duration: 87.517161ms)","trace[1168741103] 'compare' (duration: 31.002234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:36.269267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.715408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:36.269644Z","caller":"traceutil/trace.go:171","msg":"trace[1060597682] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"199.926635ms","start":"2026-04-07T12:53:36.069667Z","end":"2026-04-07T12:53:36.269594Z","steps":["trace[1060597682] 'process raft request' (duration: 89.767971ms)","trace[1060597682] 'compare' (duration: 109.261201ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:36.269991Z","caller":"traceutil/trace.go:171","msg":"trace[1149741773] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"199.674092ms","start":"2026-04-07T12:53:36.070253Z","end":"2026-04-07T12:53:36.269927Z","steps":["trace[1149741773] 'process raft request' (duration: 199.216304ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:36.270292Z","caller":"traceutil/trace.go:171","msg":"trace[42965870] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"140.300545ms","start":"2026-04-07T12:53:36.129956Z","end":"2026-04-07T12:53:36.270256Z","steps":["trace[42965870] 'process raft request' (duration: 139.867468ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:36.270409Z","caller":"traceutil/trace.go:171","msg":"trace[554340569] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"135.520129ms","start":"2026-04-07T12:53:36.134856Z","end":"2026-04-07T12:53:36.270376Z","steps":["trace[554340569] 'process raft request' (duration: 135.288985ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:36.270856Z","caller":"traceutil/trace.go:171","msg":"trace[405587643] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"132.763625ms","start":"2026-04-07T12:53:36.138033Z","end":"2026-04-07T12:53:36.270796Z","steps":["trace[405587643] 'process raft request' (duration: 132.234367ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:37.011189Z","caller":"traceutil/trace.go:171","msg":"trace[1676820887] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"155.66078ms","start":"2026-04-07T12:53:36.855499Z","end":"2026-04-07T12:53:37.01116Z","steps":["trace[1676820887] 'process raft request' (duration: 155.485547ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:37.286201Z","caller":"traceutil/trace.go:171","msg":"trace[68545260] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"266.218142ms","start":"2026-04-07T12:53:37.019939Z","end":"2026-04-07T12:53:37.286157Z","steps":["trace[68545260] 'process raft request' (duration: 245.403683ms)","trace[68545260] 'compare' (duration: 20.19846ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:37.286225Z","caller":"traceutil/trace.go:171","msg":"trace[1268813611] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1891; }","duration":"188.257915ms","start":"2026-04-07T12:53:37.097936Z","end":"2026-04-07T12:53:37.286194Z","steps":["trace[1268813611] 'read index received' (duration: 167.642949ms)","trace[1268813611] 'applied index is now lower than readState.Index' (duration: 20.613466ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:37.286499Z","caller":"traceutil/trace.go:171","msg":"trace[113395711] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"197.220139ms","start":"2026-04-07T12:53:37.089246Z","end":"2026-04-07T12:53:37.286466Z","steps":["trace[113395711] 'process raft request' (duration: 196.763911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:37.286613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.652602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T12:53:37.286662Z","caller":"traceutil/trace.go:171","msg":"trace[1319213080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1822; }","duration":"188.740573ms","start":"2026-04-07T12:53:37.097907Z","end":"2026-04-07T12:53:37.286647Z","steps":["trace[1319213080] 'agreement among raft nodes before linearized reading' (duration: 188.49442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:37.286834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.828269ms","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-07T12:53:37.286898Z","caller":"traceutil/trace.go:171","msg":"trace[86521541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1822; }","duration":"163.94328ms","start":"2026-04-07T12:53:37.122938Z","end":"2026-04-07T12:53:37.286881Z","steps":["trace[86521541] 'agreement among raft nodes before linearized reading' (duration: 163.725347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:37.543286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.731132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:37.543646Z","caller":"traceutil/trace.go:171","msg":"trace[1270725827] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"173.751336ms","start":"2026-04-07T12:53:37.369875Z","end":"2026-04-07T12:53:37.543626Z","steps":["trace[1270725827] 'process raft request' (duration: 173.507732ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:37.54398Z","caller":"traceutil/trace.go:171","msg":"trace[2052182402] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"117.578774ms","start":"2026-04-07T12:53:37.426386Z","end":"2026-04-07T12:53:37.543965Z","steps":["trace[2052182402] 'process raft request' (duration: 117.091486ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:37.543939Z","caller":"traceutil/trace.go:171","msg":"trace[1685295907] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"175.416932ms","start":"2026-04-07T12:53:37.36848Z","end":"2026-04-07T12:53:37.543897Z","steps":["trace[1685295907] 'process raft request' (duration: 46.00211ms)","trace[1685295907] 'compare' (duration: 128.346186ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:41.004914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.668038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:41.005295Z","caller":"traceutil/trace.go:171","msg":"trace[1778945651] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"274.056259ms","start":"2026-04-07T12:53:40.731009Z","end":"2026-04-07T12:53:41.005065Z","steps":["trace[1778945651] 'process raft request' (duration: 274.001518ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:41.005449Z","caller":"traceutil/trace.go:171","msg":"trace[1050503059] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"567.567243ms","start":"2026-04-07T12:53:40.437841Z","end":"2026-04-07T12:53:41.005408Z","steps":["trace[1050503059] 'process raft request' (duration: 69.315025ms)","trace[1050503059] 'compare' (duration: 497.562056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:41.005616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.255171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:53:41.005654Z","caller":"traceutil/trace.go:171","msg":"trace[1596273345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"541.317012ms","start":"2026-04-07T12:53:40.464328Z","end":"2026-04-07T12:53:41.005645Z","steps":["trace[1596273345] 'agreement among raft nodes before linearized reading' (duration: 541.1976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.005681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:40.464318Z","time spent":"541.356833ms","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:53:41.005677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:40.437827Z","time spent":"567.726885ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:41.005505Z","caller":"traceutil/trace.go:171","msg":"trace[572118713] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"541.139309ms","start":"2026-04-07T12:53:40.464353Z","end":"2026-04-07T12:53:41.005492Z","steps":["trace[572118713] 'read index received' (duration: 42.727859ms)","trace[572118713] 'applied index is now lower than readState.Index' (duration: 498.41036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:41.006052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.493089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:53:41.006121Z","caller":"traceutil/trace.go:171","msg":"trace[2143867638] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1854; }","duration":"471.58884ms","start":"2026-04-07T12:53:40.534512Z","end":"2026-04-07T12:53:41.006101Z","steps":["trace[2143867638] 'agreement among raft nodes before linearized reading' (duration: 471.478668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.006162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:40.534503Z","time spent":"471.649941ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:53:41.006204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.470905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54bd1785-845a-4b4e-b85b-f44e69cd9627\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T12:53:41.00625Z","caller":"traceutil/trace.go:171","msg":"trace[123705662] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54bd1785-845a-4b4e-b85b-f44e69cd9627; range_end:; response_count:1; response_revision:1854; }","duration":"166.555147ms","start":"2026-04-07T12:53:40.839682Z","end":"2026-04-07T12:53:41.006237Z","steps":["trace[123705662] 'agreement among raft nodes before linearized reading' (duration: 166.422255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.006365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.758936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54bd1785-845a-4b4e-b85b-f44e69cd9627\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-07T12:53:41.006374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.251699ms","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-07T12:53:41.006445Z","caller":"traceutil/trace.go:171","msg":"trace[830826196] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54bd1785-845a-4b4e-b85b-f44e69cd9627; range_end:; response_count:1; response_revision:1854; }","duration":"447.887359ms","start":"2026-04-07T12:53:40.558529Z","end":"2026-04-07T12:53:41.006416Z","steps":["trace[830826196] 'agreement among raft nodes before linearized reading' (duration: 447.647275ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:41.006453Z","caller":"traceutil/trace.go:171","msg":"trace[1336132045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1854; }","duration":"247.377181ms","start":"2026-04-07T12:53:40.759055Z","end":"2026-04-07T12:53:41.006432Z","steps":["trace[1336132045] 'agreement among raft nodes before linearized reading' (duration: 247.073576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.006525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:40.558496Z","time spent":"448.017341ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54bd1785-845a-4b4e-b85b-f44e69cd9627\" "} {"level":"warn","ts":"2026-04-07T12:53:41.810222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.560423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:41.8103Z","caller":"traceutil/trace.go:171","msg":"trace[1496010470] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"799.243353ms","start":"2026-04-07T12:53:41.011043Z","end":"2026-04-07T12:53:41.810286Z","steps":["trace[1496010470] 'process raft request' (duration: 557.411565ms)","trace[1496010470] 'compare' (duration: 241.307929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:41.81034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.011021Z","time spent":"799.301364ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:41.884841Z","caller":"traceutil/trace.go:171","msg":"trace[1596972357] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"586.960985ms","start":"2026-04-07T12:53:41.297857Z","end":"2026-04-07T12:53:41.884818Z","steps":["trace[1596972357] 'process raft request' (duration: 586.865604ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:41.884979Z","caller":"traceutil/trace.go:171","msg":"trace[1566302913] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1927; }","duration":"767.520009ms","start":"2026-04-07T12:53:41.117448Z","end":"2026-04-07T12:53:41.884968Z","steps":["trace[1566302913] 'read index received' (duration: 451.016557ms)","trace[1566302913] 'applied index is now lower than readState.Index' (duration: 316.501291ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:41.885098Z","caller":"traceutil/trace.go:171","msg":"trace[1853257798] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"586.166893ms","start":"2026-04-07T12:53:41.298896Z","end":"2026-04-07T12:53:41.885063Z","steps":["trace[1853257798] 'process raft request' (duration: 585.881939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.885209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.298877Z","time spent":"586.254405ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:53:41.885327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.797805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T12:53:41.885369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.901264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:53:41.885413Z","caller":"traceutil/trace.go:171","msg":"trace[550803645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1858; }","duration":"323.938247ms","start":"2026-04-07T12:53:41.561455Z","end":"2026-04-07T12:53:41.885393Z","steps":["trace[550803645] 'agreement among raft nodes before linearized reading' (duration: 323.733204ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:41.885451Z","caller":"traceutil/trace.go:171","msg":"trace[883562747] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1858; }","duration":"768.054636ms","start":"2026-04-07T12:53:41.117382Z","end":"2026-04-07T12:53:41.885437Z","steps":["trace[883562747] 'agreement among raft nodes before linearized reading' (duration: 767.885464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.885459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.561437Z","time spent":"324.013158ms","remote":"127.0.0.1:55704","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-07T12:53:41.8855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"warn","ts":"2026-04-07T12:53:41.885519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.117361Z","time spent":"768.146548ms","remote":"127.0.0.1:60902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":13,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-04-07T12:53:41.885542Z","caller":"traceutil/trace.go:171","msg":"trace[1259519466] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1858; }","duration":"197.168691ms","start":"2026-04-07T12:53:41.688362Z","end":"2026-04-07T12:53:41.885531Z","steps":["trace[1259519466] 'agreement among raft nodes before linearized reading' (duration: 197.038989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.885726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.730556ms","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-07T12:53:41.885775Z","caller":"traceutil/trace.go:171","msg":"trace[818704138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1858; }","duration":"508.782887ms","start":"2026-04-07T12:53:41.376981Z","end":"2026-04-07T12:53:41.885764Z","steps":["trace[818704138] 'agreement among raft nodes before linearized reading' (duration: 508.673125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.885808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.376946Z","time spent":"508.849119ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T12:53:41.885846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.857208ms","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-07T12:53:41.885879Z","caller":"traceutil/trace.go:171","msg":"trace[1468172749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1858; }","duration":"508.91979ms","start":"2026-04-07T12:53:41.37695Z","end":"2026-04-07T12:53:41.88587Z","steps":["trace[1468172749] 'agreement among raft nodes before linearized reading' (duration: 508.832889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.885903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.376937Z","time spent":"508.95932ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T12:53:41.884867Z","caller":"traceutil/trace.go:171","msg":"trace[1807178945] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"870.362852ms","start":"2026-04-07T12:53:41.014471Z","end":"2026-04-07T12:53:41.884834Z","steps":["trace[1807178945] 'process raft request' (duration: 870.119288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.886054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.067257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-07T12:53:41.88614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.014458Z","time spent":"871.643951ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:41.886121Z","caller":"traceutil/trace.go:171","msg":"trace[552508256] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1858; }","duration":"508.153307ms","start":"2026-04-07T12:53:41.377954Z","end":"2026-04-07T12:53:41.886107Z","steps":["trace[552508256] 'agreement among raft nodes before linearized reading' (duration: 507.962175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.886217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.377943Z","time spent":"508.262499ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T12:53:41.886263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.297827Z","time spent":"587.160848ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:53:41.88657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.025847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T12:53:41.886612Z","caller":"traceutil/trace.go:171","msg":"trace[704265471] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"571.102889ms","start":"2026-04-07T12:53:41.3155Z","end":"2026-04-07T12:53:41.886603Z","steps":["trace[704265471] 'agreement among raft nodes before linearized reading' (duration: 569.647236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:41.886644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.315485Z","time spent":"571.151459ms","remote":"127.0.0.1:46842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T12:53:42.361863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.454808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:42.362009Z","caller":"traceutil/trace.go:171","msg":"trace[150154108] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"469.288569ms","start":"2026-04-07T12:53:41.892696Z","end":"2026-04-07T12:53:42.361985Z","steps":["trace[150154108] 'process raft request' (duration: 28.620277ms)","trace[150154108] 'compare' (duration: 440.342376ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:42.362097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.892673Z","time spent":"469.37909ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:42.396213Z","caller":"traceutil/trace.go:171","msg":"trace[1003140740] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"503.233594ms","start":"2026-04-07T12:53:41.892956Z","end":"2026-04-07T12:53:42.396189Z","steps":["trace[1003140740] 'process raft request' (duration: 503.058232ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:42.396459Z","caller":"traceutil/trace.go:171","msg":"trace[2107732138] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1931; }","duration":"497.729308ms","start":"2026-04-07T12:53:41.898708Z","end":"2026-04-07T12:53:42.396437Z","steps":["trace[2107732138] 'read index received' (duration: 22.621902ms)","trace[2107732138] 'applied index is now lower than readState.Index' (duration: 475.106246ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:42.39651Z","caller":"traceutil/trace.go:171","msg":"trace[458580328] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"503.478328ms","start":"2026-04-07T12:53:41.893012Z","end":"2026-04-07T12:53:42.396491Z","steps":["trace[458580328] 'process raft request' (duration: 503.135433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:42.396621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.893006Z","time spent":"503.559809ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:53:42.396635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.912181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:53:42.396697Z","caller":"traceutil/trace.go:171","msg":"trace[134248912] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1862; }","duration":"498.002233ms","start":"2026-04-07T12:53:41.898678Z","end":"2026-04-07T12:53:42.39668Z","steps":["trace[134248912] 'agreement among raft nodes before linearized reading' (duration: 497.890361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:42.396623Z","caller":"traceutil/trace.go:171","msg":"trace[1523779378] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"503.541248ms","start":"2026-04-07T12:53:41.893053Z","end":"2026-04-07T12:53:42.396595Z","steps":["trace[1523779378] 'process raft request' (duration: 503.299125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:42.396752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.898669Z","time spent":"498.071603ms","remote":"127.0.0.1:55872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-07T12:53:42.396822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.387433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54bd1785-845a-4b4e-b85b-f44e69cd9627\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-07T12:53:42.397153Z","caller":"traceutil/trace.go:171","msg":"trace[1168530911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54bd1785-845a-4b4e-b85b-f44e69cd9627; range_end:; response_count:1; response_revision:1862; }","duration":"386.636968ms","start":"2026-04-07T12:53:42.010381Z","end":"2026-04-07T12:53:42.397018Z","steps":["trace[1168530911] 'agreement among raft nodes before linearized reading' (duration: 386.229001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:42.39688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.893025Z","time spent":"503.783942ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:53:42.397261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:42.010361Z","time spent":"386.879032ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54bd1785-845a-4b4e-b85b-f44e69cd9627\" "} {"level":"warn","ts":"2026-04-07T12:53:42.397195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:41.892946Z","time spent":"503.342036ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:53:43.122156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.803172ms","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-07T12:53:43.122229Z","caller":"traceutil/trace.go:171","msg":"trace[406573423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"110.918934ms","start":"2026-04-07T12:53:43.011293Z","end":"2026-04-07T12:53:43.122212Z","steps":["trace[406573423] 'range keys from in-memory index tree' (duration: 110.649969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:43.122383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.99318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:53:43.122463Z","caller":"traceutil/trace.go:171","msg":"trace[1198435405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"658.123052ms","start":"2026-04-07T12:53:42.464321Z","end":"2026-04-07T12:53:43.122444Z","steps":["trace[1198435405] 'range keys from in-memory index tree' (duration: 657.904488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:43.122532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:42.464298Z","time spent":"658.207133ms","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:53:43.122504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.488721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T12:53:43.122677Z","caller":"traceutil/trace.go:171","msg":"trace[663869416] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"227.694655ms","start":"2026-04-07T12:53:42.894954Z","end":"2026-04-07T12:53:43.122649Z","steps":["trace[663869416] 'range keys from in-memory index tree' (duration: 227.260489ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:43.123267Z","caller":"traceutil/trace.go:171","msg":"trace[15811284] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"111.890699ms","start":"2026-04-07T12:53:43.011351Z","end":"2026-04-07T12:53:43.123242Z","steps":["trace[15811284] 'process raft request' (duration: 103.713232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:48.874591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.791024ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T12:53:50.367425Z","caller":"traceutil/trace.go:171","msg":"trace[1406940771] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"522.640945ms","start":"2026-04-07T12:53:49.844765Z","end":"2026-04-07T12:53:50.367406Z","steps":["trace[1406940771] 'process raft request' (duration: 522.492793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:50.367548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:49.844748Z","time spent":"522.740606ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:50.374908Z","caller":"traceutil/trace.go:171","msg":"trace[699961031] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"465.549705ms","start":"2026-04-07T12:53:49.909336Z","end":"2026-04-07T12:53:50.374886Z","steps":["trace[699961031] 'process raft request' (duration: 465.382032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:50.375047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:49.909319Z","time spent":"465.645666ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:50.375095Z","caller":"traceutil/trace.go:171","msg":"trace[893714974] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2003; }","duration":"205.370444ms","start":"2026-04-07T12:53:50.169695Z","end":"2026-04-07T12:53:50.375066Z","steps":["trace[893714974] 'read index received' (duration: 197.565396ms)","trace[893714974] 'applied index is now lower than readState.Index' (duration: 7.803328ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:50.375171Z","caller":"traceutil/trace.go:171","msg":"trace[1053640797] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"450.983735ms","start":"2026-04-07T12:53:49.924159Z","end":"2026-04-07T12:53:50.375142Z","steps":["trace[1053640797] 'process raft request' (duration: 450.697611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:50.375292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.138479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-04-07T12:53:50.375282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:49.924138Z","time spent":"451.071306ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:50.375317Z","caller":"traceutil/trace.go:171","msg":"trace[1932540046] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1931; }","duration":"141.19176ms","start":"2026-04-07T12:53:50.234118Z","end":"2026-04-07T12:53:50.375309Z","steps":["trace[1932540046] 'agreement among raft nodes before linearized reading' (duration: 141.072118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:50.375691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.981182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T12:53:50.375768Z","caller":"traceutil/trace.go:171","msg":"trace[2132061015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1931; }","duration":"206.085684ms","start":"2026-04-07T12:53:50.169657Z","end":"2026-04-07T12:53:50.375742Z","steps":["trace[2132061015] 'agreement among raft nodes before linearized reading' (duration: 205.509856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:51.425748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.74222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-hnr2c\" ","response":"range_response_count:1 size:5812"} {"level":"info","ts":"2026-04-07T12:53:51.425827Z","caller":"traceutil/trace.go:171","msg":"trace[2540884] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-hnr2c; range_end:; response_count:1; response_revision:1940; }","duration":"129.872403ms","start":"2026-04-07T12:53:51.295937Z","end":"2026-04-07T12:53:51.42581Z","steps":["trace[2540884] 'range keys from in-memory index tree' (duration: 129.586078ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:55.326054Z","caller":"traceutil/trace.go:171","msg":"trace[1609891479] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"102.239656ms","start":"2026-04-07T12:53:55.223787Z","end":"2026-04-07T12:53:55.326027Z","steps":["trace[1609891479] 'process raft request' (duration: 101.961292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:55.624315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.084806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:55.624385Z","caller":"traceutil/trace.go:171","msg":"trace[636242169] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"151.99065ms","start":"2026-04-07T12:53:55.472383Z","end":"2026-04-07T12:53:55.624374Z","steps":["trace[636242169] 'read index received' (duration: 47.291µs)","trace[636242169] 'applied index is now lower than readState.Index' (duration: 151.942629ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:55.62454Z","caller":"traceutil/trace.go:171","msg":"trace[1332044410] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"293.769528ms","start":"2026-04-07T12:53:55.330763Z","end":"2026-04-07T12:53:55.624532Z","steps":["trace[1332044410] 'process raft request' (duration: 34.274556ms)","trace[1332044410] 'compare' (duration: 258.988135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:55.624741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.322844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T12:53:55.624858Z","caller":"traceutil/trace.go:171","msg":"trace[402517221] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1974; }","duration":"152.490507ms","start":"2026-04-07T12:53:55.472336Z","end":"2026-04-07T12:53:55.624826Z","steps":["trace[402517221] 'agreement among raft nodes before linearized reading' (duration: 152.118732ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:57.670905Z","caller":"traceutil/trace.go:171","msg":"trace[517905285] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2057; }","duration":"195.815884ms","start":"2026-04-07T12:53:57.475042Z","end":"2026-04-07T12:53:57.670857Z","steps":["trace[517905285] 'read index received' (duration: 195.323376ms)","trace[517905285] 'applied index is now lower than readState.Index' (duration: 490.568µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:57.671077Z","caller":"traceutil/trace.go:171","msg":"trace[665994022] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"339.252401ms","start":"2026-04-07T12:53:57.33178Z","end":"2026-04-07T12:53:57.671033Z","steps":["trace[665994022] 'process raft request' (duration: 338.747433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:57.671304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:57.331759Z","time spent":"339.413664ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:53:57.671544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.477593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-04-07T12:53:57.671593Z","caller":"traceutil/trace.go:171","msg":"trace[1093002659] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1982; }","duration":"196.605945ms","start":"2026-04-07T12:53:57.474972Z","end":"2026-04-07T12:53:57.671578Z","steps":["trace[1093002659] 'agreement among raft nodes before linearized reading' (duration: 196.094898ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.56011Z","caller":"traceutil/trace.go:171","msg":"trace[691622898] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"144.868656ms","start":"2026-04-07T12:54:00.415211Z","end":"2026-04-07T12:54:00.56008Z","steps":["trace[691622898] 'process raft request' (duration: 144.694734ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.647128Z","caller":"traceutil/trace.go:171","msg":"trace[1497806704] linearizableReadLoop","detail":"{readStateIndex:2074; appliedIndex:2073; }","duration":"224.66837ms","start":"2026-04-07T12:54:00.422431Z","end":"2026-04-07T12:54:00.6471Z","steps":["trace[1497806704] 'read index received' (duration: 138.019236ms)","trace[1497806704] 'applied index is now lower than readState.Index' (duration: 86.648004ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:00.64728Z","caller":"traceutil/trace.go:171","msg":"trace[597656982] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"229.926257ms","start":"2026-04-07T12:54:00.41732Z","end":"2026-04-07T12:54:00.647246Z","steps":["trace[597656982] 'process raft request' (duration: 201.431325ms)","trace[597656982] 'compare' (duration: 28.202258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:54:00.647448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.008235ms","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-07T12:54:00.647483Z","caller":"traceutil/trace.go:171","msg":"trace[1594459737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1997; }","duration":"225.110736ms","start":"2026-04-07T12:54:00.422363Z","end":"2026-04-07T12:54:00.647474Z","steps":["trace[1594459737] 'agreement among raft nodes before linearized reading' (duration: 224.904903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:00.648735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.990229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:54:00.648815Z","caller":"traceutil/trace.go:171","msg":"trace[2039744826] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1997; }","duration":"141.130251ms","start":"2026-04-07T12:54:00.507667Z","end":"2026-04-07T12:54:00.648797Z","steps":["trace[2039744826] 'agreement among raft nodes before linearized reading' (duration: 140.34175ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.83988Z","caller":"traceutil/trace.go:171","msg":"trace[370255683] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2074; }","duration":"155.798294ms","start":"2026-04-07T12:54:00.684054Z","end":"2026-04-07T12:54:00.839852Z","steps":["trace[370255683] 'read index received' (duration: 32.848405ms)","trace[370255683] 'applied index is now lower than readState.Index' (duration: 122.948919ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:00.839986Z","caller":"traceutil/trace.go:171","msg":"trace[1188679288] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"188.287405ms","start":"2026-04-07T12:54:00.651645Z","end":"2026-04-07T12:54:00.839932Z","steps":["trace[1188679288] 'process raft request' (duration: 108.622122ms)","trace[1188679288] 'compare' (duration: 79.42735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:54:00.840128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.676127ms","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-07T12:54:00.840146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.291171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-07T12:54:00.840164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.080468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T12:54:00.84018Z","caller":"traceutil/trace.go:171","msg":"trace[1900166913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1999; }","duration":"154.773238ms","start":"2026-04-07T12:54:00.685394Z","end":"2026-04-07T12:54:00.840167Z","steps":["trace[1900166913] 'agreement among raft nodes before linearized reading' (duration: 154.632016ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.840187Z","caller":"traceutil/trace.go:171","msg":"trace[809407391] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1999; }","duration":"120.389451ms","start":"2026-04-07T12:54:00.719786Z","end":"2026-04-07T12:54:00.840175Z","steps":["trace[809407391] 'agreement among raft nodes before linearized reading' (duration: 120.245829ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.840222Z","caller":"traceutil/trace.go:171","msg":"trace[2062650372] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1999; }","duration":"156.163779ms","start":"2026-04-07T12:54:00.684037Z","end":"2026-04-07T12:54:00.840201Z","steps":["trace[2062650372] 'agreement among raft nodes before linearized reading' (duration: 155.959566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:00.840223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.767072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:00.840262Z","caller":"traceutil/trace.go:171","msg":"trace[189251012] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1999; }","duration":"103.854462ms","start":"2026-04-07T12:54:00.736397Z","end":"2026-04-07T12:54:00.840252Z","steps":["trace[189251012] 'agreement among raft nodes before linearized reading' (duration: 103.756461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:00.840257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.935874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:54:00.84032Z","caller":"traceutil/trace.go:171","msg":"trace[464464631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1999; }","duration":"151.060965ms","start":"2026-04-07T12:54:00.689247Z","end":"2026-04-07T12:54:00.840308Z","steps":["trace[464464631] 'agreement among raft nodes before linearized reading' (duration: 150.810542ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.987126Z","caller":"traceutil/trace.go:171","msg":"trace[1365505894] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"142.38794ms","start":"2026-04-07T12:54:00.844717Z","end":"2026-04-07T12:54:00.987105Z","steps":["trace[1365505894] 'process raft request' (duration: 135.539651ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.987504Z","caller":"traceutil/trace.go:171","msg":"trace[194467187] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"142.156196ms","start":"2026-04-07T12:54:00.845315Z","end":"2026-04-07T12:54:00.987471Z","steps":["trace[194467187] 'process raft request' (duration: 141.874462ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.987535Z","caller":"traceutil/trace.go:171","msg":"trace[2090138609] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"141.479197ms","start":"2026-04-07T12:54:00.846015Z","end":"2026-04-07T12:54:00.987494Z","steps":["trace[2090138609] 'process raft request' (duration: 141.387015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:03.36438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.498744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:54:03.364464Z","caller":"traceutil/trace.go:171","msg":"trace[1473334227] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2018; }","duration":"131.624855ms","start":"2026-04-07T12:54:03.232817Z","end":"2026-04-07T12:54:03.364442Z","steps":["trace[1473334227] 'count revisions from in-memory index tree' (duration: 131.409302ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:10.925377Z","caller":"traceutil/trace.go:171","msg":"trace[1494677318] linearizableReadLoop","detail":"{readStateIndex:2140; appliedIndex:2139; }","duration":"154.346381ms","start":"2026-04-07T12:54:10.771003Z","end":"2026-04-07T12:54:10.925349Z","steps":["trace[1494677318] 'read index received' (duration: 154.033657ms)","trace[1494677318] 'applied index is now lower than readState.Index' (duration: 311.694µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:54:10.925689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.673525ms","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-07T12:54:10.925755Z","caller":"traceutil/trace.go:171","msg":"trace[420624855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2061; }","duration":"154.791608ms","start":"2026-04-07T12:54:10.770951Z","end":"2026-04-07T12:54:10.925743Z","steps":["trace[420624855] 'agreement among raft nodes before linearized reading' (duration: 154.558394ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:10.925474Z","caller":"traceutil/trace.go:171","msg":"trace[136257890] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"213.211656ms","start":"2026-04-07T12:54:10.712233Z","end":"2026-04-07T12:54:10.925445Z","steps":["trace[136257890] 'process raft request' (duration: 212.921772ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:16.804791Z","caller":"traceutil/trace.go:171","msg":"trace[1591739165] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"125.663978ms","start":"2026-04-07T12:54:16.679067Z","end":"2026-04-07T12:54:16.804731Z","steps":["trace[1591739165] 'process raft request' (duration: 52.003765ms)","trace[1591739165] 'compare' (duration: 73.43211ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:17.179519Z","caller":"traceutil/trace.go:171","msg":"trace[1184858550] linearizableReadLoop","detail":"{readStateIndex:2173; appliedIndex:2172; }","duration":"212.838422ms","start":"2026-04-07T12:54:16.966645Z","end":"2026-04-07T12:54:17.179484Z","steps":["trace[1184858550] 'read index received' (duration: 212.516268ms)","trace[1184858550] 'applied index is now lower than readState.Index' (duration: 320.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:17.179717Z","caller":"traceutil/trace.go:171","msg":"trace[172776728] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"221.613322ms","start":"2026-04-07T12:54:16.957943Z","end":"2026-04-07T12:54:17.179557Z","steps":["trace[172776728] 'process raft request' (duration: 221.324108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:17.180136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.46975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T12:54:17.180198Z","caller":"traceutil/trace.go:171","msg":"trace[758604869] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2093; }","duration":"213.580752ms","start":"2026-04-07T12:54:16.966598Z","end":"2026-04-07T12:54:17.180179Z","steps":["trace[758604869] 'agreement among raft nodes before linearized reading' (duration: 213.055784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:17.180527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.846204ms","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-07T12:54:17.180684Z","caller":"traceutil/trace.go:171","msg":"trace[1122599901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2093; }","duration":"179.027836ms","start":"2026-04-07T12:54:17.00164Z","end":"2026-04-07T12:54:17.180668Z","steps":["trace[1122599901] 'agreement among raft nodes before linearized reading' (duration: 178.063233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:17.180848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.538563ms","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-07T12:54:17.180901Z","caller":"traceutil/trace.go:171","msg":"trace[1122687016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2093; }","duration":"101.636345ms","start":"2026-04-07T12:54:17.079249Z","end":"2026-04-07T12:54:17.180886Z","steps":["trace[1122687016] 'agreement among raft nodes before linearized reading' (duration: 100.496719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:17.180763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.155815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T12:54:17.181067Z","caller":"traceutil/trace.go:171","msg":"trace[2024414096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2093; }","duration":"100.453188ms","start":"2026-04-07T12:54:17.080534Z","end":"2026-04-07T12:54:17.180988Z","steps":["trace[2024414096] 'agreement among raft nodes before linearized reading' (duration: 99.191401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:17.181386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.028254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-07T12:54:17.181539Z","caller":"traceutil/trace.go:171","msg":"trace[1108962662] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2093; }","duration":"106.220136ms","start":"2026-04-07T12:54:17.075299Z","end":"2026-04-07T12:54:17.18152Z","steps":["trace[1108962662] 'agreement among raft nodes before linearized reading' (duration: 104.464422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:17.181602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.667001ms","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-07T12:54:17.182162Z","caller":"traceutil/trace.go:171","msg":"trace[1106975474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2093; }","duration":"176.216507ms","start":"2026-04-07T12:54:17.005859Z","end":"2026-04-07T12:54:17.182075Z","steps":["trace[1106975474] 'agreement among raft nodes before linearized reading' (duration: 173.924676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:17.889798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743854545568475,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T12:54:18.178512Z","caller":"traceutil/trace.go:171","msg":"trace[2145177041] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"992.107794ms","start":"2026-04-07T12:54:17.186368Z","end":"2026-04-07T12:54:18.178476Z","steps":["trace[2145177041] 'process raft request' (duration: 991.780459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:18.178712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.186346Z","time spent":"992.257246ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:54:18.534931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.672105ms","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-07T12:54:18.535128Z","caller":"traceutil/trace.go:171","msg":"trace[1532246343] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2094; }","duration":"162.895638ms","start":"2026-04-07T12:54:18.372199Z","end":"2026-04-07T12:54:18.535095Z","steps":["trace[1532246343] 'range keys from in-memory index tree' (duration: 162.641065ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:18.623744Z","caller":"traceutil/trace.go:171","msg":"trace[1615060554] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"1.437249814s","start":"2026-04-07T12:54:17.18646Z","end":"2026-04-07T12:54:18.62371Z","steps":["trace[1615060554] 'process raft request' (duration: 1.43698038s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:18.623957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.186428Z","time spent":"1.437416696s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:54:18.624287Z","caller":"traceutil/trace.go:171","msg":"trace[1426964365] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"1.436862178s","start":"2026-04-07T12:54:17.187401Z","end":"2026-04-07T12:54:18.624263Z","steps":["trace[1426964365] 'process raft request' (duration: 1.436248759s)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:18.624368Z","caller":"traceutil/trace.go:171","msg":"trace[2105191386] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"1.436666456s","start":"2026-04-07T12:54:17.187688Z","end":"2026-04-07T12:54:18.624354Z","steps":["trace[2105191386] 'process raft request' (duration: 1.436509354s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:18.624462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.187673Z","time spent":"1.436744587s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:54:18.624497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235340263s","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-07T12:54:18.62432Z","caller":"traceutil/trace.go:171","msg":"trace[446401405] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2173; }","duration":"1.235173851s","start":"2026-04-07T12:54:17.389114Z","end":"2026-04-07T12:54:18.624288Z","steps":["trace[446401405] 'read index received' (duration: 789.26391ms)","trace[446401405] 'applied index is now lower than readState.Index' (duration: 445.908641ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:18.62457Z","caller":"traceutil/trace.go:171","msg":"trace[504649848] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2099; }","duration":"1.235524485s","start":"2026-04-07T12:54:17.389017Z","end":"2026-04-07T12:54:18.624541Z","steps":["trace[504649848] 'agreement among raft nodes before linearized reading' (duration: 1.235383933s)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:18.62457Z","caller":"traceutil/trace.go:171","msg":"trace[627412818] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"1.436915988s","start":"2026-04-07T12:54:17.187636Z","end":"2026-04-07T12:54:18.624552Z","steps":["trace[627412818] 'process raft request' (duration: 1.436406512s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:18.624615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.38898Z","time spent":"1.235624307s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:54:18.62464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.187627Z","time spent":"1.436976709s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:54:18.624692Z","caller":"traceutil/trace.go:171","msg":"trace[1214743050] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"823.299571ms","start":"2026-04-07T12:54:17.801367Z","end":"2026-04-07T12:54:18.624666Z","steps":["trace[1214743050] 'process raft request' (duration: 822.868655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:18.624839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.801327Z","time spent":"823.425922ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:54:18.625009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.187391Z","time spent":"1.436961489s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:54:19.465469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000020415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T12:54:19.465648Z","caller":"traceutil/trace.go:171","msg":"trace[499772646] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000216567s","start":"2026-04-07T12:54:17.465365Z","end":"2026-04-07T12:54:19.465582Z","steps":["trace[499772646] 'agreement among raft nodes before linearized reading' (duration: 1.999997195s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.465721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.465358Z","time spent":"2.000349729s","remote":"127.0.0.1:55474","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-07T12:54:20.114593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46305355s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T12:54:20.115418Z","caller":"traceutil/trace.go:171","msg":"trace[2124524269] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"1.431055952s","start":"2026-04-07T12:54:18.684336Z","end":"2026-04-07T12:54:20.115392Z","steps":["trace[2124524269] 'process raft request' (duration: 1.430375592s)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:20.115916Z","caller":"traceutil/trace.go:171","msg":"trace[1753095131] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"1.330889881s","start":"2026-04-07T12:54:18.784996Z","end":"2026-04-07T12:54:20.115886Z","steps":["trace[1753095131] 'process raft request' (duration: 1.330327664s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.115991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.18876592s","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-07T12:54:20.116053Z","caller":"traceutil/trace.go:171","msg":"trace[320283608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2101; }","duration":"2.188902962s","start":"2026-04-07T12:54:17.927136Z","end":"2026-04-07T12:54:20.116039Z","steps":["trace[320283608] 'agreement among raft nodes before linearized reading' (duration: 2.188619599s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.116151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:18.784969Z","time spent":"1.331084125s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:54:20.116205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.9271Z","time spent":"2.189090305s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T12:54:20.116242Z","caller":"traceutil/trace.go:171","msg":"trace[424329866] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"930.552431ms","start":"2026-04-07T12:54:19.185664Z","end":"2026-04-07T12:54:20.116216Z","steps":["trace[424329866] 'process raft request' (duration: 930.132105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.116254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.652312671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T12:54:20.115613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:18.6843Z","time spent":"1.431182024s","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:54:20.116387Z","caller":"traceutil/trace.go:171","msg":"trace[1444616555] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2102; }","duration":"2.652424392s","start":"2026-04-07T12:54:17.46388Z","end":"2026-04-07T12:54:20.116304Z","steps":["trace[1444616555] 'agreement among raft nodes before linearized reading' (duration: 2.652298209s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.116405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:19.185626Z","time spent":"930.676834ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:54:20.116441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.463856Z","time spent":"2.652571684s","remote":"127.0.0.1:55608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:54:20.116501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925237573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T12:54:20.116569Z","caller":"traceutil/trace.go:171","msg":"trace[450232372] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2102; }","duration":"1.925343975s","start":"2026-04-07T12:54:18.191206Z","end":"2026-04-07T12:54:20.11655Z","steps":["trace[450232372] 'agreement among raft nodes before linearized reading' (duration: 1.924792957s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.116644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:18.191182Z","time spent":"1.925444006s","remote":"127.0.0.1:46842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T12:54:20.116101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.326086725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-04-07T12:54:20.115307Z","caller":"traceutil/trace.go:171","msg":"trace[1627556652] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2179; }","duration":"1.490674028s","start":"2026-04-07T12:54:18.624581Z","end":"2026-04-07T12:54:20.115255Z","steps":["trace[1627556652] 'read index received' (duration: 27.011059ms)","trace[1627556652] 'applied index is now lower than readState.Index' (duration: 1.463658949s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:54:20.117271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.367119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:54:20.117298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.808353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:54:20.117311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.682644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T12:54:20.117338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.111406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:54:20.118017Z","caller":"traceutil/trace.go:171","msg":"trace[759828191] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2101; }","duration":"2.327000328s","start":"2026-04-07T12:54:17.789924Z","end":"2026-04-07T12:54:20.116924Z","steps":["trace[759828191] 'agreement among raft nodes before linearized reading' (duration: 2.325855892s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.11796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.488669456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T12:54:20.118088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.789904Z","time spent":"2.328166663s","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-07T12:54:20.118094Z","caller":"traceutil/trace.go:171","msg":"trace[1354502100] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2102; }","duration":"2.490142206s","start":"2026-04-07T12:54:17.627932Z","end":"2026-04-07T12:54:20.118075Z","steps":["trace[1354502100] 'agreement among raft nodes before linearized reading' (duration: 2.488087677s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.11815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:17.627905Z","time spent":"2.490226727s","remote":"127.0.0.1:58948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-04-07T12:54:20.118311Z","caller":"traceutil/trace.go:171","msg":"trace[1319595201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2102; }","duration":"650.491971ms","start":"2026-04-07T12:54:19.466811Z","end":"2026-04-07T12:54:20.117303Z","steps":["trace[1319595201] 'agreement among raft nodes before linearized reading' (duration: 650.42183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.118361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:19.466787Z","time spent":"651.561915ms","remote":"127.0.0.1:55470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T12:54:20.118867Z","caller":"traceutil/trace.go:171","msg":"trace[82913629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2102; }","duration":"249.897215ms","start":"2026-04-07T12:54:19.867435Z","end":"2026-04-07T12:54:20.117332Z","steps":["trace[82913629] 'agreement among raft nodes before linearized reading' (duration: 249.811104ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:20.119045Z","caller":"traceutil/trace.go:171","msg":"trace[2021619930] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2102; }","duration":"472.783135ms","start":"2026-04-07T12:54:19.64457Z","end":"2026-04-07T12:54:20.117353Z","steps":["trace[2021619930] 'agreement among raft nodes before linearized reading' (duration: 472.678154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.119118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:19.64455Z","time spent":"474.545691ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-07T12:54:20.1199Z","caller":"traceutil/trace.go:171","msg":"trace[1735197695] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2102; }","duration":"642.196337ms","start":"2026-04-07T12:54:19.475161Z","end":"2026-04-07T12:54:20.117358Z","steps":["trace[1735197695] 'agreement among raft nodes before linearized reading' (duration: 642.146857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.119974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:19.47511Z","time spent":"644.846734ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:54:20.633005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743854545568488,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T12:54:21.002869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.96244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:54:21.003287Z","caller":"traceutil/trace.go:171","msg":"trace[474113106] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"879.414856ms","start":"2026-04-07T12:54:20.123769Z","end":"2026-04-07T12:54:21.003184Z","steps":["trace[474113106] 'process raft request' (duration: 696.067531ms)","trace[474113106] 'compare' (duration: 182.035577ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:54:21.003418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.123745Z","time spent":"879.633618ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:54:21.003607Z","caller":"traceutil/trace.go:171","msg":"trace[1251784109] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"878.748747ms","start":"2026-04-07T12:54:20.124811Z","end":"2026-04-07T12:54:21.00356Z","steps":["trace[1251784109] 'process raft request' (duration: 878.165779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.003907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.124801Z","time spent":"878.965469ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:54:21.003905Z","caller":"traceutil/trace.go:171","msg":"trace[1320339600] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2183; }","duration":"871.60805ms","start":"2026-04-07T12:54:20.132282Z","end":"2026-04-07T12:54:21.00389Z","steps":["trace[1320339600] 'read index received' (duration: 687.627157ms)","trace[1320339600] 'applied index is now lower than readState.Index' (duration: 183.975223ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:21.004092Z","caller":"traceutil/trace.go:171","msg":"trace[193548389] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"370.829256ms","start":"2026-04-07T12:54:20.633239Z","end":"2026-04-07T12:54:21.004069Z","steps":["trace[193548389] 'process raft request' (duration: 369.817782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.004186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.037625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:54:21.004214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.633223Z","time spent":"370.926817ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:54:21.00428Z","caller":"traceutil/trace.go:171","msg":"trace[1627933694] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2105; }","duration":"836.151687ms","start":"2026-04-07T12:54:20.168086Z","end":"2026-04-07T12:54:21.004238Z","steps":["trace[1627933694] 'agreement among raft nodes before linearized reading' (duration: 836.037736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.004343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.168072Z","time spent":"836.253479ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:54:21.004453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.900847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T12:54:21.004503Z","caller":"traceutil/trace.go:171","msg":"trace[486879272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2105; }","duration":"373.989529ms","start":"2026-04-07T12:54:20.630496Z","end":"2026-04-07T12:54:21.004486Z","steps":["trace[486879272] 'agreement among raft nodes before linearized reading' (duration: 373.839477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.004535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.630466Z","time spent":"374.062079ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T12:54:21.004563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.338338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T12:54:21.004691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.010938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-07T12:54:21.004697Z","caller":"traceutil/trace.go:171","msg":"trace[559089553] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2105; }","duration":"447.638661ms","start":"2026-04-07T12:54:20.557027Z","end":"2026-04-07T12:54:21.004665Z","steps":["trace[559089553] 'agreement among raft nodes before linearized reading' (duration: 447.371247ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:21.004755Z","caller":"traceutil/trace.go:171","msg":"trace[1520597233] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2105; }","duration":"518.075379ms","start":"2026-04-07T12:54:20.486664Z","end":"2026-04-07T12:54:21.00474Z","steps":["trace[1520597233] 'agreement among raft nodes before linearized reading' (duration: 517.925797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.00476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.162901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T12:54:21.004775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.556973Z","time spent":"447.785643ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-07T12:54:21.004803Z","caller":"traceutil/trace.go:171","msg":"trace[1374058109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2105; }","duration":"374.220702ms","start":"2026-04-07T12:54:20.630573Z","end":"2026-04-07T12:54:21.004794Z","steps":["trace[1374058109] 'agreement among raft nodes before linearized reading' (duration: 374.030449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.00486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.630568Z","time spent":"374.272032ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T12:54:21.004919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.140403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T12:54:21.004807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.486585Z","time spent":"518.209781ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-07T12:54:21.005004Z","caller":"traceutil/trace.go:171","msg":"trace[497648802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2105; }","duration":"821.232924ms","start":"2026-04-07T12:54:20.18372Z","end":"2026-04-07T12:54:21.004953Z","steps":["trace[497648802] 'agreement among raft nodes before linearized reading' (duration: 821.112122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.005062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.183685Z","time spent":"821.364296ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T12:54:21.005143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.250565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-07T12:54:21.005307Z","caller":"traceutil/trace.go:171","msg":"trace[485793005] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2105; }","duration":"375.629001ms","start":"2026-04-07T12:54:20.629636Z","end":"2026-04-07T12:54:21.005265Z","steps":["trace[485793005] 'agreement among raft nodes before linearized reading' (duration: 374.903681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.005214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.077979ms","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-07T12:54:21.005357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.629607Z","time spent":"375.738263ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T12:54:21.005469Z","caller":"traceutil/trace.go:171","msg":"trace[1668199088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2105; }","duration":"374.461826ms","start":"2026-04-07T12:54:20.630906Z","end":"2026-04-07T12:54:21.005368Z","steps":["trace[1668199088] 'agreement among raft nodes before linearized reading' (duration: 373.689395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.005204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.910728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:21.005568Z","caller":"traceutil/trace.go:171","msg":"trace[1921646304] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2105; }","duration":"873.275253ms","start":"2026-04-07T12:54:20.132273Z","end":"2026-04-07T12:54:21.005548Z","steps":["trace[1921646304] 'agreement among raft nodes before linearized reading' (duration: 872.849617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:21.005533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.630898Z","time spent":"374.615048ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:54:21.006203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:20.132263Z","time spent":"873.356534ms","remote":"127.0.0.1:55872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-07T12:54:34.306343Z","caller":"traceutil/trace.go:171","msg":"trace[2100450055] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"131.427128ms","start":"2026-04-07T12:54:34.174864Z","end":"2026-04-07T12:54:34.306291Z","steps":["trace[2100450055] 'process raft request' (duration: 85.245431ms)","trace[2100450055] 'compare' (duration: 45.884833ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:22.768155Z","caller":"traceutil/trace.go:171","msg":"trace[333548308] linearizableReadLoop","detail":"{readStateIndex:2639; appliedIndex:2638; }","duration":"149.645889ms","start":"2026-04-07T12:55:22.618481Z","end":"2026-04-07T12:55:22.768127Z","steps":["trace[333548308] 'read index received' (duration: 149.506477ms)","trace[333548308] 'applied index is now lower than readState.Index' (duration: 137.762µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:22.768202Z","caller":"traceutil/trace.go:171","msg":"trace[435945165] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"266.374227ms","start":"2026-04-07T12:55:22.501784Z","end":"2026-04-07T12:55:22.768158Z","steps":["trace[435945165] 'process raft request' (duration: 266.123235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:22.769275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.746383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:55:22.769409Z","caller":"traceutil/trace.go:171","msg":"trace[1397832197] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2544; }","duration":"150.945175ms","start":"2026-04-07T12:55:22.618437Z","end":"2026-04-07T12:55:22.769382Z","steps":["trace[1397832197] 'agreement among raft nodes before linearized reading' (duration: 149.794401ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:23.468027Z","caller":"traceutil/trace.go:171","msg":"trace[346046747] linearizableReadLoop","detail":"{readStateIndex:2641; appliedIndex:2640; }","duration":"133.888087ms","start":"2026-04-07T12:55:23.334109Z","end":"2026-04-07T12:55:23.467997Z","steps":["trace[346046747] 'read index received' (duration: 133.611024ms)","trace[346046747] 'applied index is now lower than readState.Index' (duration: 275.993µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:23.468377Z","caller":"traceutil/trace.go:171","msg":"trace[1521965515] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"217.047226ms","start":"2026-04-07T12:55:23.251316Z","end":"2026-04-07T12:55:23.468363Z","steps":["trace[1521965515] 'process raft request' (duration: 216.49691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.468765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.646455ms","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-07T12:55:23.468802Z","caller":"traceutil/trace.go:171","msg":"trace[1983703842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2546; }","duration":"134.762297ms","start":"2026-04-07T12:55:23.334031Z","end":"2026-04-07T12:55:23.468793Z","steps":["trace[1983703842] 'agreement among raft nodes before linearized reading' (duration: 134.553014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.469271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.646212ms","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-07T12:55:23.469306Z","caller":"traceutil/trace.go:171","msg":"trace[1509672894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2546; }","duration":"114.679353ms","start":"2026-04-07T12:55:23.354617Z","end":"2026-04-07T12:55:23.469296Z","steps":["trace[1509672894] 'agreement among raft nodes before linearized reading' (duration: 114.594942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.469433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.735421ms","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-07T12:55:23.46945Z","caller":"traceutil/trace.go:171","msg":"trace[861331052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2546; }","duration":"113.783031ms","start":"2026-04-07T12:55:23.355662Z","end":"2026-04-07T12:55:23.469445Z","steps":["trace[861331052] 'agreement among raft nodes before linearized reading' (duration: 113.725791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.469623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.072351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T12:55:23.469734Z","caller":"traceutil/trace.go:171","msg":"trace[2135528353] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2546; }","duration":"126.217492ms","start":"2026-04-07T12:55:23.34348Z","end":"2026-04-07T12:55:23.469698Z","steps":["trace[2135528353] 'agreement among raft nodes before linearized reading' (duration: 126.02362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.469718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.984639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:55:23.46983Z","caller":"traceutil/trace.go:171","msg":"trace[1313892212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2546; }","duration":"116.1349ms","start":"2026-04-07T12:55:23.353668Z","end":"2026-04-07T12:55:23.469803Z","steps":["trace[1313892212] 'agreement among raft nodes before linearized reading' (duration: 115.802027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.469929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.195565ms","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-07T12:55:23.470019Z","caller":"traceutil/trace.go:171","msg":"trace[745751189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2546; }","duration":"127.308046ms","start":"2026-04-07T12:55:23.34266Z","end":"2026-04-07T12:55:23.469968Z","steps":["trace[745751189] 'agreement among raft nodes before linearized reading' (duration: 126.267553ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:43.794965Z","caller":"traceutil/trace.go:171","msg":"trace[124863042] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"128.324419ms","start":"2026-04-07T12:55:43.666605Z","end":"2026-04-07T12:55:43.79493Z","steps":["trace[124863042] 'process raft request' (duration: 29.878646ms)","trace[124863042] 'compare' (duration: 98.124658ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:43.795384Z","caller":"traceutil/trace.go:171","msg":"trace[65721944] transaction","detail":"{read_only:false; response_revision:2663; number_of_response:1; }","duration":"125.797519ms","start":"2026-04-07T12:55:43.669573Z","end":"2026-04-07T12:55:43.79537Z","steps":["trace[65721944] 'process raft request' (duration: 125.620757ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:43.795378Z","caller":"traceutil/trace.go:171","msg":"trace[817522507] linearizableReadLoop","detail":"{readStateIndex:2765; appliedIndex:2761; }","duration":"120.552715ms","start":"2026-04-07T12:55:43.674803Z","end":"2026-04-07T12:55:43.795355Z","steps":["trace[817522507] 'read index received' (duration: 21.699148ms)","trace[817522507] 'applied index is now lower than readState.Index' (duration: 98.852627ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:43.795446Z","caller":"traceutil/trace.go:171","msg":"trace[1666018731] transaction","detail":"{read_only:false; response_revision:2664; number_of_response:1; }","duration":"125.829178ms","start":"2026-04-07T12:55:43.669596Z","end":"2026-04-07T12:55:43.795425Z","steps":["trace[1666018731] 'process raft request' (duration: 125.714387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:43.795608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.607003ms","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-07T12:55:43.795645Z","caller":"traceutil/trace.go:171","msg":"trace[482207295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2664; }","duration":"118.705475ms","start":"2026-04-07T12:55:43.676928Z","end":"2026-04-07T12:55:43.795634Z","steps":["trace[482207295] 'agreement among raft nodes before linearized reading' (duration: 118.578853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:43.795635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.812568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T12:55:43.795748Z","caller":"traceutil/trace.go:171","msg":"trace[1320157848] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2664; }","duration":"120.979961ms","start":"2026-04-07T12:55:43.674754Z","end":"2026-04-07T12:55:43.795734Z","steps":["trace[1320157848] 'agreement among raft nodes before linearized reading' (duration: 120.721448ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:43.795658Z","caller":"traceutil/trace.go:171","msg":"trace[1497173448] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"126.78925ms","start":"2026-04-07T12:55:43.66852Z","end":"2026-04-07T12:55:43.795309Z","steps":["trace[1497173448] 'process raft request' (duration: 126.335275ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:44.057794Z","caller":"traceutil/trace.go:171","msg":"trace[844662598] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"255.035667ms","start":"2026-04-07T12:55:43.802727Z","end":"2026-04-07T12:55:44.057763Z","steps":["trace[844662598] 'process raft request' (duration: 215.496316ms)","trace[844662598] 'compare' (duration: 39.059175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:49.96176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.615758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-07T12:55:49.961901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.388655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T12:55:49.961919Z","caller":"traceutil/trace.go:171","msg":"trace[2125874784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2690; }","duration":"130.85654ms","start":"2026-04-07T12:55:49.831027Z","end":"2026-04-07T12:55:49.961884Z","steps":["trace[2125874784] 'range keys from in-memory index tree' (duration: 130.295714ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:49.961994Z","caller":"traceutil/trace.go:171","msg":"trace[1881850142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2690; }","duration":"130.517997ms","start":"2026-04-07T12:55:49.831455Z","end":"2026-04-07T12:55:49.961973Z","steps":["trace[1881850142] 'range keys from in-memory index tree' (duration: 130.201863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:49.961845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.717308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-07T12:55:49.962444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.518366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:55:49.962505Z","caller":"traceutil/trace.go:171","msg":"trace[1612517517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2690; }","duration":"129.626697ms","start":"2026-04-07T12:55:49.832863Z","end":"2026-04-07T12:55:49.962489Z","steps":["trace[1612517517] 'range keys from in-memory index tree' (duration: 129.350994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:49.962542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.237994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T12:55:49.962678Z","caller":"traceutil/trace.go:171","msg":"trace[1353164247] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2690; }","duration":"103.429336ms","start":"2026-04-07T12:55:49.85921Z","end":"2026-04-07T12:55:49.962639Z","steps":["trace[1353164247] 'range keys from in-memory index tree' (duration: 102.973971ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:49.962561Z","caller":"traceutil/trace.go:171","msg":"trace[531898286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2690; }","duration":"131.039722ms","start":"2026-04-07T12:55:49.83106Z","end":"2026-04-07T12:55:49.962099Z","steps":["trace[531898286] 'range keys from in-memory index tree' (duration: 130.547217ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:50.221826Z","caller":"traceutil/trace.go:171","msg":"trace[929908006] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2793; }","duration":"138.930525ms","start":"2026-04-07T12:55:50.082875Z","end":"2026-04-07T12:55:50.221806Z","steps":["trace[929908006] 'read index received' (duration: 45.759421ms)","trace[929908006] 'applied index is now lower than readState.Index' (duration: 93.170264ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:50.222016Z","caller":"traceutil/trace.go:171","msg":"trace[1435196152] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"254.335713ms","start":"2026-04-07T12:55:49.967668Z","end":"2026-04-07T12:55:50.222004Z","steps":["trace[1435196152] 'process raft request' (duration: 254.05999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:50.222031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.148407ms","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-07T12:55:50.222058Z","caller":"traceutil/trace.go:171","msg":"trace[1532084099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2695; }","duration":"139.230519ms","start":"2026-04-07T12:55:50.082819Z","end":"2026-04-07T12:55:50.22205Z","steps":["trace[1532084099] 'agreement among raft nodes before linearized reading' (duration: 139.070647ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:50.222127Z","caller":"traceutil/trace.go:171","msg":"trace[522507478] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"254.377823ms","start":"2026-04-07T12:55:49.967741Z","end":"2026-04-07T12:55:50.222119Z","steps":["trace[522507478] 'process raft request' (duration: 254.02416ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:50.2221Z","caller":"traceutil/trace.go:171","msg":"trace[1576855356] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"254.538305ms","start":"2026-04-07T12:55:49.967381Z","end":"2026-04-07T12:55:50.221919Z","steps":["trace[1576855356] 'process raft request' (duration: 161.315361ms)","trace[1576855356] 'compare' (duration: 92.8342ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:50.221857Z","caller":"traceutil/trace.go:171","msg":"trace[395591088] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"254.374453ms","start":"2026-04-07T12:55:49.96745Z","end":"2026-04-07T12:55:50.221825Z","steps":["trace[395591088] 'process raft request' (duration: 254.236002ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:56.4034Z","caller":"traceutil/trace.go:171","msg":"trace[958959482] linearizableReadLoop","detail":"{readStateIndex:2827; appliedIndex:2826; }","duration":"217.460564ms","start":"2026-04-07T12:55:56.185911Z","end":"2026-04-07T12:55:56.403372Z","steps":["trace[958959482] 'read index received' (duration: 217.199951ms)","trace[958959482] 'applied index is now lower than readState.Index' (duration: 259.183µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:56.403543Z","caller":"traceutil/trace.go:171","msg":"trace[1097287515] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"364.933493ms","start":"2026-04-07T12:55:56.038595Z","end":"2026-04-07T12:55:56.403528Z","steps":["trace[1097287515] 'process raft request' (duration: 364.59516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:56.403676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.038555Z","time spent":"365.024265ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:55:56.40402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.172876ms","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-07T12:55:56.404082Z","caller":"traceutil/trace.go:171","msg":"trace[1413784641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2724; }","duration":"153.286927ms","start":"2026-04-07T12:55:56.25078Z","end":"2026-04-07T12:55:56.404067Z","steps":["trace[1413784641] 'agreement among raft nodes before linearized reading' (duration: 153.090555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:56.404135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.858493ms","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-07T12:55:56.404115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.819321ms","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-07T12:55:56.40419Z","caller":"traceutil/trace.go:171","msg":"trace[1188087209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2724; }","duration":"153.946306ms","start":"2026-04-07T12:55:56.250231Z","end":"2026-04-07T12:55:56.404177Z","steps":["trace[1188087209] 'agreement among raft nodes before linearized reading' (duration: 153.632111ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:56.404246Z","caller":"traceutil/trace.go:171","msg":"trace[1998701935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2724; }","duration":"152.987615ms","start":"2026-04-07T12:55:56.251233Z","end":"2026-04-07T12:55:56.40422Z","steps":["trace[1998701935] 'agreement among raft nodes before linearized reading' (duration: 152.64363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:56.404321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.423405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a4145b78447e68\" ","response":"range_response_count:1 size:777"} {"level":"info","ts":"2026-04-07T12:55:56.40437Z","caller":"traceutil/trace.go:171","msg":"trace[1810159040] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a4145b78447e68; range_end:; response_count:1; response_revision:2724; }","duration":"218.524957ms","start":"2026-04-07T12:55:56.185831Z","end":"2026-04-07T12:55:56.404356Z","steps":["trace[1810159040] 'agreement among raft nodes before linearized reading' (duration: 218.362095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:56.404371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.554961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-07T12:55:56.404426Z","caller":"traceutil/trace.go:171","msg":"trace[502953638] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2724; }","duration":"153.618991ms","start":"2026-04-07T12:55:56.250796Z","end":"2026-04-07T12:55:56.404415Z","steps":["trace[502953638] 'agreement among raft nodes before linearized reading' (duration: 153.087704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:57.081756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.238856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:55:57.081947Z","caller":"traceutil/trace.go:171","msg":"trace[438366194] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"673.854536ms","start":"2026-04-07T12:55:56.40805Z","end":"2026-04-07T12:55:57.081904Z","steps":["trace[438366194] 'process raft request' (duration: 442.307525ms)","trace[438366194] 'compare' (duration: 230.64034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:57.08205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.408026Z","time spent":"673.971428ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":762,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:57.199941Z","caller":"traceutil/trace.go:171","msg":"trace[1844136504] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"791.544563ms","start":"2026-04-07T12:55:56.408365Z","end":"2026-04-07T12:55:57.199909Z","steps":["trace[1844136504] 'process raft request' (duration: 790.212817ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:57.200349Z","caller":"traceutil/trace.go:171","msg":"trace[1680592859] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"789.092333ms","start":"2026-04-07T12:55:56.41121Z","end":"2026-04-07T12:55:57.200303Z","steps":["trace[1680592859] 'process raft request' (duration: 788.622327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:57.200637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.411196Z","time spent":"789.286906ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:55:57.200577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.40835Z","time spent":"791.849886ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:57.201354Z","caller":"traceutil/trace.go:171","msg":"trace[1041342798] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"789.896333ms","start":"2026-04-07T12:55:56.411416Z","end":"2026-04-07T12:55:57.201313Z","steps":["trace[1041342798] 'process raft request' (duration: 789.791782ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:57.201691Z","caller":"traceutil/trace.go:171","msg":"trace[700860437] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"790.205967ms","start":"2026-04-07T12:55:56.41147Z","end":"2026-04-07T12:55:57.201676Z","steps":["trace[700860437] 'process raft request' (duration: 789.790762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:57.201736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.411406Z","time spent":"790.081445ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:57.201922Z","caller":"traceutil/trace.go:171","msg":"trace[913891194] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"633.620209ms","start":"2026-04-07T12:55:56.568289Z","end":"2026-04-07T12:55:57.201909Z","steps":["trace[913891194] 'process raft request' (duration: 633.199745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:57.20194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.999541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:55:57.202005Z","caller":"traceutil/trace.go:171","msg":"trace[1406189581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2731; }","duration":"738.129202ms","start":"2026-04-07T12:55:56.463863Z","end":"2026-04-07T12:55:57.201992Z","steps":["trace[1406189581] 'agreement among raft nodes before linearized reading' (duration: 738.015861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:57.202035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.463831Z","time spent":"738.197443ms","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:55:57.202047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.568235Z","time spent":"633.775532ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:57.201682Z","caller":"traceutil/trace.go:171","msg":"trace[574046235] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"790.298988ms","start":"2026-04-07T12:55:56.411361Z","end":"2026-04-07T12:55:57.20166Z","steps":["trace[574046235] 'process raft request' (duration: 789.660811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:57.201815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.411464Z","time spent":"790.275748ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:55:57.202271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.411338Z","time spent":"790.892074ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:57.20185Z","caller":"traceutil/trace.go:171","msg":"trace[510000942] linearizableReadLoop","detail":"{readStateIndex:2833; appliedIndex:2827; }","duration":"737.89259ms","start":"2026-04-07T12:55:56.463943Z","end":"2026-04-07T12:55:57.201836Z","steps":["trace[510000942] 'read index received' (duration: 386.378616ms)","trace[510000942] 'applied index is now lower than readState.Index' (duration: 351.512804ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:57.205497Z","caller":"traceutil/trace.go:171","msg":"trace[2084921621] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"262.502174ms","start":"2026-04-07T12:55:56.942974Z","end":"2026-04-07T12:55:57.205476Z","steps":["trace[2084921621] 'process raft request' (duration: 262.311822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:57.205951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.635491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-07T12:55:57.206028Z","caller":"traceutil/trace.go:171","msg":"trace[1488328706] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2732; }","duration":"321.759493ms","start":"2026-04-07T12:55:56.884248Z","end":"2026-04-07T12:55:57.206008Z","steps":["trace[1488328706] 'agreement among raft nodes before linearized reading' (duration: 321.573881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:57.206085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:56.884219Z","time spent":"321.852424ms","remote":"127.0.0.1:55644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-07T12:56:02.406462Z","caller":"traceutil/trace.go:171","msg":"trace[967256281] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"166.811369ms","start":"2026-04-07T12:56:02.239607Z","end":"2026-04-07T12:56:02.406418Z","steps":["trace[967256281] 'process raft request' (duration: 166.500906ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:08.880463Z","caller":"traceutil/trace.go:171","msg":"trace[702642586] linearizableReadLoop","detail":"{readStateIndex:2896; appliedIndex:2895; }","duration":"114.601302ms","start":"2026-04-07T12:56:08.765823Z","end":"2026-04-07T12:56:08.880424Z","steps":["trace[702642586] 'read index received' (duration: 41.448676ms)","trace[702642586] 'applied index is now lower than readState.Index' (duration: 73.150506ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:08.880873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.897106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-07T12:56:08.880927Z","caller":"traceutil/trace.go:171","msg":"trace[1418845939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2790; }","duration":"115.150709ms","start":"2026-04-07T12:56:08.76576Z","end":"2026-04-07T12:56:08.880911Z","steps":["trace[1418845939] 'agreement among raft nodes before linearized reading' (duration: 114.791724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:09.552021Z","caller":"traceutil/trace.go:171","msg":"trace[846522792] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"110.210349ms","start":"2026-04-07T12:56:09.44178Z","end":"2026-04-07T12:56:09.55199Z","steps":["trace[846522792] 'process raft request' (duration: 109.502161ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:09.552563Z","caller":"traceutil/trace.go:171","msg":"trace[1880885914] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"108.62492ms","start":"2026-04-07T12:56:09.443899Z","end":"2026-04-07T12:56:09.552524Z","steps":["trace[1880885914] 'process raft request' (duration: 107.541078ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:19.765714Z","caller":"traceutil/trace.go:171","msg":"trace[1499296561] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"128.937395ms","start":"2026-04-07T12:56:19.636738Z","end":"2026-04-07T12:56:19.765675Z","steps":["trace[1499296561] 'process raft request' (duration: 128.614657ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:19.766558Z","caller":"traceutil/trace.go:171","msg":"trace[1467954751] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"129.156849ms","start":"2026-04-07T12:56:19.637368Z","end":"2026-04-07T12:56:19.766524Z","steps":["trace[1467954751] 'process raft request' (duration: 128.946014ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:26.248546Z","caller":"traceutil/trace.go:171","msg":"trace[903777520] transaction","detail":"{read_only:false; response_revision:3196; number_of_response:1; }","duration":"101.653637ms","start":"2026-04-07T12:57:26.146834Z","end":"2026-04-07T12:57:26.248488Z","steps":["trace[903777520] 'process raft request' (duration: 101.018774ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:26.489702Z","caller":"traceutil/trace.go:171","msg":"trace[1167841426] transaction","detail":"{read_only:false; response_revision:3198; number_of_response:1; }","duration":"235.641188ms","start":"2026-04-07T12:57:26.254018Z","end":"2026-04-07T12:57:26.489659Z","steps":["trace[1167841426] 'process raft request' (duration: 143.420322ms)","trace[1167841426] 'compare' (duration: 91.839869ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:53.322756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.605026ms","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-07T12:59:53.322878Z","caller":"traceutil/trace.go:171","msg":"trace[1657633282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4157; }","duration":"183.776128ms","start":"2026-04-07T12:59:53.139083Z","end":"2026-04-07T12:59:53.322859Z","steps":["trace[1657633282] 'range keys from in-memory index tree' (duration: 183.346772ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:58.213195Z","caller":"traceutil/trace.go:171","msg":"trace[1691121388] transaction","detail":"{read_only:false; response_revision:4502; number_of_response:1; }","duration":"126.843084ms","start":"2026-04-07T12:59:58.086265Z","end":"2026-04-07T12:59:58.213108Z","steps":["trace[1691121388] 'process raft request' (duration: 70.965947ms)","trace[1691121388] 'compare' (duration: 54.939193ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:59:58.2141Z","caller":"traceutil/trace.go:171","msg":"trace[192595364] linearizableReadLoop","detail":"{readStateIndex:4676; appliedIndex:4674; }","duration":"114.429032ms","start":"2026-04-07T12:59:58.099538Z","end":"2026-04-07T12:59:58.213967Z","steps":["trace[192595364] 'read index received' (duration: 57.702623ms)","trace[192595364] 'applied index is now lower than readState.Index' (duration: 56.717239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:58.214333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.764416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:3025"} {"level":"info","ts":"2026-04-07T12:59:58.214483Z","caller":"traceutil/trace.go:171","msg":"trace[434569324] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4504; }","duration":"114.943638ms","start":"2026-04-07T12:59:58.09951Z","end":"2026-04-07T12:59:58.214453Z","steps":["trace[434569324] 'agreement among raft nodes before linearized reading' (duration: 114.607574ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:58.21462Z","caller":"traceutil/trace.go:171","msg":"trace[958331290] transaction","detail":"{read_only:false; response_revision:4503; number_of_response:1; }","duration":"123.25608ms","start":"2026-04-07T12:59:58.09133Z","end":"2026-04-07T12:59:58.214586Z","steps":["trace[958331290] 'process raft request' (duration: 121.84113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:04.767075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.217355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T13:00:04.767159Z","caller":"traceutil/trace.go:171","msg":"trace[716570964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4644; }","duration":"140.342427ms","start":"2026-04-07T13:00:04.626799Z","end":"2026-04-07T13:00:04.767141Z","steps":["trace[716570964] 'range keys from in-memory index tree' (duration: 140.078373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:04.767595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.670681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7465"} {"level":"info","ts":"2026-04-07T13:00:04.767639Z","caller":"traceutil/trace.go:171","msg":"trace[1169442261] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4644; }","duration":"140.822954ms","start":"2026-04-07T13:00:04.6268Z","end":"2026-04-07T13:00:04.767623Z","steps":["trace[1169442261] 'range keys from in-memory index tree' (duration: 140.386498ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:04.91924Z","caller":"traceutil/trace.go:171","msg":"trace[1774630112] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"136.599664ms","start":"2026-04-07T13:00:04.782592Z","end":"2026-04-07T13:00:04.919192Z","steps":["trace[1774630112] 'process raft request' (duration: 136.341939ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:04.920111Z","caller":"traceutil/trace.go:171","msg":"trace[424770668] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"137.641728ms","start":"2026-04-07T13:00:04.782434Z","end":"2026-04-07T13:00:04.920076Z","steps":["trace[424770668] 'process raft request' (duration: 136.42949ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:04.919014Z","caller":"traceutil/trace.go:171","msg":"trace[1527913167] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"141.347141ms","start":"2026-04-07T13:00:04.777618Z","end":"2026-04-07T13:00:04.918965Z","steps":["trace[1527913167] 'process raft request' (duration: 135.412105ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:16.980041Z","caller":"traceutil/trace.go:171","msg":"trace[418931466] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"111.686574ms","start":"2026-04-07T13:00:16.868339Z","end":"2026-04-07T13:00:16.980026Z","steps":["trace[418931466] 'process raft request' (duration: 110.620648ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:22.455511Z","caller":"traceutil/trace.go:171","msg":"trace[1459482831] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"112.342933ms","start":"2026-04-07T13:00:22.343117Z","end":"2026-04-07T13:00:22.45546Z","steps":["trace[1459482831] 'process raft request' (duration: 112.13682ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:22.483369Z","caller":"traceutil/trace.go:171","msg":"trace[1097906377] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"126.789459ms","start":"2026-04-07T13:00:22.356551Z","end":"2026-04-07T13:00:22.48334Z","steps":["trace[1097906377] 'process raft request' (duration: 126.492345ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:23.160271Z","caller":"traceutil/trace.go:171","msg":"trace[206668750] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5091; }","duration":"147.988401ms","start":"2026-04-07T13:00:23.012255Z","end":"2026-04-07T13:00:23.160243Z","steps":["trace[206668750] 'read index received' (duration: 61.664819ms)","trace[206668750] 'applied index is now lower than readState.Index' (duration: 86.320482ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:23.160483Z","caller":"traceutil/trace.go:171","msg":"trace[757206980] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"113.320416ms","start":"2026-04-07T13:00:23.047146Z","end":"2026-04-07T13:00:23.160466Z","steps":["trace[757206980] 'process raft request' (duration: 112.975862ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:23.160542Z","caller":"traceutil/trace.go:171","msg":"trace[364419866] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"200.026064ms","start":"2026-04-07T13:00:22.960493Z","end":"2026-04-07T13:00:23.160519Z","steps":["trace[364419866] 'process raft request' (duration: 113.459089ms)","trace[364419866] 'compare' (duration: 86.041708ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:23.160768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.500598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T13:00:23.160797Z","caller":"traceutil/trace.go:171","msg":"trace[400599828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4910; }","duration":"148.567599ms","start":"2026-04-07T13:00:23.012222Z","end":"2026-04-07T13:00:23.160789Z","steps":["trace[400599828] 'agreement among raft nodes before linearized reading' (duration: 148.450177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.160863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.736786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-07T13:00:23.160922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.764364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-07T13:00:23.160925Z","caller":"traceutil/trace.go:171","msg":"trace[614847055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4910; }","duration":"147.867228ms","start":"2026-04-07T13:00:23.013041Z","end":"2026-04-07T13:00:23.160908Z","steps":["trace[614847055] 'agreement among raft nodes before linearized reading' (duration: 147.693116ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:23.160955Z","caller":"traceutil/trace.go:171","msg":"trace[677989472] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4910; }","duration":"132.811365ms","start":"2026-04-07T13:00:23.028133Z","end":"2026-04-07T13:00:23.160944Z","steps":["trace[677989472] 'agreement among raft nodes before linearized reading' (duration: 132.694193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.16168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.263765ms","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-07T13:00:23.161933Z","caller":"traceutil/trace.go:171","msg":"trace[1109885006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4910; }","duration":"148.70384ms","start":"2026-04-07T13:00:23.013095Z","end":"2026-04-07T13:00:23.161798Z","steps":["trace[1109885006] 'agreement among raft nodes before linearized reading' (duration: 148.100081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.781968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.49437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:00:23.782345Z","caller":"traceutil/trace.go:171","msg":"trace[2003778204] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"612.78174ms","start":"2026-04-07T13:00:23.169542Z","end":"2026-04-07T13:00:23.782324Z","steps":["trace[2003778204] 'process raft request' (duration: 612.595277ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:23.782442Z","caller":"traceutil/trace.go:171","msg":"trace[656384865] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"612.667057ms","start":"2026-04-07T13:00:23.169703Z","end":"2026-04-07T13:00:23.78237Z","steps":["trace[656384865] 'process raft request' (duration: 612.579256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.782594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.16952Z","time spent":"612.872721ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1868,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:23.782605Z","caller":"traceutil/trace.go:171","msg":"trace[677948016] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"613.198105ms","start":"2026-04-07T13:00:23.169367Z","end":"2026-04-07T13:00:23.782565Z","steps":["trace[677948016] 'process raft request' (duration: 440.026656ms)","trace[677948016] 'compare' (duration: 172.258417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:23.7826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.169697Z","time spent":"612.821969ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:00:23.782786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.169358Z","time spent":"613.339837ms","remote":"127.0.0.1:55622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1282,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:23.7832Z","caller":"traceutil/trace.go:171","msg":"trace[1091040494] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"612.487565ms","start":"2026-04-07T13:00:23.170698Z","end":"2026-04-07T13:00:23.783185Z","steps":["trace[1091040494] 'process raft request' (duration: 611.640903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.783935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.170689Z","time spent":"613.179485ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1454,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:23.783316Z","caller":"traceutil/trace.go:171","msg":"trace[229371552] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"611.791525ms","start":"2026-04-07T13:00:23.171448Z","end":"2026-04-07T13:00:23.78324Z","steps":["trace[229371552] 'process raft request' (duration: 611.623943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.784213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.171439Z","time spent":"612.676787ms","remote":"127.0.0.1:55622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:23.783537Z","caller":"traceutil/trace.go:171","msg":"trace[401860058] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5094; }","duration":"608.833983ms","start":"2026-04-07T13:00:23.174692Z","end":"2026-04-07T13:00:23.783526Z","steps":["trace[401860058] 'read index received' (duration: 26.746171ms)","trace[401860058] 'applied index is now lower than readState.Index' (duration: 582.086482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:23.784659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.261223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:00:23.784757Z","caller":"traceutil/trace.go:171","msg":"trace[76683907] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4919; }","duration":"415.405054ms","start":"2026-04-07T13:00:23.369332Z","end":"2026-04-07T13:00:23.784737Z","steps":["trace[76683907] 'agreement among raft nodes before linearized reading' (duration: 415.262062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.784865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.369312Z","time spent":"415.537607ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:00:23.784981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.847651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T13:00:23.785034Z","caller":"traceutil/trace.go:171","msg":"trace[889055769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4919; }","duration":"291.961154ms","start":"2026-04-07T13:00:23.49306Z","end":"2026-04-07T13:00:23.785022Z","steps":["trace[889055769] 'agreement among raft nodes before linearized reading' (duration: 291.829452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.784793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.483511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T13:00:23.78535Z","caller":"traceutil/trace.go:171","msg":"trace[1084300846] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4919; }","duration":"206.091579ms","start":"2026-04-07T13:00:23.579246Z","end":"2026-04-07T13:00:23.785337Z","steps":["trace[1084300846] 'agreement among raft nodes before linearized reading' (duration: 205.43124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.785317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.533501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T13:00:23.783601Z","caller":"traceutil/trace.go:171","msg":"trace[624291652] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"471.12269ms","start":"2026-04-07T13:00:23.31247Z","end":"2026-04-07T13:00:23.783592Z","steps":["trace[624291652] 'process raft request' (duration: 470.919597ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:23.785537Z","caller":"traceutil/trace.go:171","msg":"trace[260031129] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:4919; }","duration":"353.715993ms","start":"2026-04-07T13:00:23.431771Z","end":"2026-04-07T13:00:23.785487Z","steps":["trace[260031129] 'agreement among raft nodes before linearized reading' (duration: 353.46709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.785654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.431712Z","time spent":"353.927497ms","remote":"127.0.0.1:55492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-04-07T13:00:23.785548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.312435Z","time spent":"473.070087ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:23.782727Z","caller":"traceutil/trace.go:171","msg":"trace[1911458007] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"613.049463ms","start":"2026-04-07T13:00:23.169667Z","end":"2026-04-07T13:00:23.782716Z","steps":["trace[1911458007] 'process raft request' (duration: 612.574727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.788112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.169661Z","time spent":"618.371909ms","remote":"127.0.0.1:55704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:23.783512Z","caller":"traceutil/trace.go:171","msg":"trace[1571643169] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"602.668105ms","start":"2026-04-07T13:00:23.180815Z","end":"2026-04-07T13:00:23.783483Z","steps":["trace[1571643169] 'process raft request' (duration: 602.368111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.788338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.180802Z","time spent":"607.502294ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:00:23.783762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.058026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-07T13:00:23.78855Z","caller":"traceutil/trace.go:171","msg":"trace[1696845351] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4919; }","duration":"613.944826ms","start":"2026-04-07T13:00:23.174584Z","end":"2026-04-07T13:00:23.788529Z","steps":["trace[1696845351] 'agreement among raft nodes before linearized reading' (duration: 608.977115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:23.788854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:23.174459Z","time spent":"614.374592ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-07T13:00:24.128817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.806816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:00:24.129801Z","caller":"traceutil/trace.go:171","msg":"trace[2134921709] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"251.984292ms","start":"2026-04-07T13:00:23.877776Z","end":"2026-04-07T13:00:24.12976Z","steps":["trace[2134921709] 'process raft request' (duration: 35.119231ms)","trace[2134921709] 'compare' (duration: 215.669914ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:24.129199Z","caller":"traceutil/trace.go:171","msg":"trace[1459419726] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5110; }","duration":"185.322332ms","start":"2026-04-07T13:00:23.94385Z","end":"2026-04-07T13:00:24.129173Z","steps":["trace[1459419726] 'read index received' (duration: 61.231µs)","trace[1459419726] 'applied index is now lower than readState.Index' (duration: 185.259501ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:24.13009Z","caller":"traceutil/trace.go:171","msg":"trace[364903113] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"249.497867ms","start":"2026-04-07T13:00:23.880579Z","end":"2026-04-07T13:00:24.130077Z","steps":["trace[364903113] 'process raft request' (duration: 248.444562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:24.130326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.464878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:00:24.130416Z","caller":"traceutil/trace.go:171","msg":"trace[313704794] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4927; }","duration":"186.56789ms","start":"2026-04-07T13:00:23.943825Z","end":"2026-04-07T13:00:24.130393Z","steps":["trace[313704794] 'agreement among raft nodes before linearized reading' (duration: 186.417248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:24.130404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.514488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-07T13:00:24.13052Z","caller":"traceutil/trace.go:171","msg":"trace[1250169586] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4927; }","duration":"167.68997ms","start":"2026-04-07T13:00:23.962804Z","end":"2026-04-07T13:00:24.130494Z","steps":["trace[1250169586] 'agreement among raft nodes before linearized reading' (duration: 167.354025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:24.13053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.311899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-07T13:00:24.13062Z","caller":"traceutil/trace.go:171","msg":"trace[2093468595] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4927; }","duration":"147.412401ms","start":"2026-04-07T13:00:23.983195Z","end":"2026-04-07T13:00:24.130608Z","steps":["trace[2093468595] 'agreement among raft nodes before linearized reading' (duration: 147.203988ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:25.391743Z","caller":"traceutil/trace.go:171","msg":"trace[516815683] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5118; }","duration":"186.421725ms","start":"2026-04-07T13:00:25.205295Z","end":"2026-04-07T13:00:25.391717Z","steps":["trace[516815683] 'read index received' (duration: 186.13911ms)","trace[516815683] 'applied index is now lower than readState.Index' (duration: 281.165µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:25.39183Z","caller":"traceutil/trace.go:171","msg":"trace[1094231014] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"224.480676ms","start":"2026-04-07T13:00:25.167315Z","end":"2026-04-07T13:00:25.391796Z","steps":["trace[1094231014] 'process raft request' (duration: 224.214982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:25.392145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.840401ms","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-07T13:00:25.392201Z","caller":"traceutil/trace.go:171","msg":"trace[789987193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4934; }","duration":"186.942002ms","start":"2026-04-07T13:00:25.205246Z","end":"2026-04-07T13:00:25.392188Z","steps":["trace[789987193] 'agreement among raft nodes before linearized reading' (duration: 186.561726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:30.133344Z","caller":"traceutil/trace.go:171","msg":"trace[977479814] linearizableReadLoop","detail":"{readStateIndex:5158; appliedIndex:5157; }","duration":"227.483345ms","start":"2026-04-07T13:00:29.905844Z","end":"2026-04-07T13:00:30.133327Z","steps":["trace[977479814] 'read index received' (duration: 227.317163ms)","trace[977479814] 'applied index is now lower than readState.Index' (duration: 165.432µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:30.13352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.663417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T13:00:30.133581Z","caller":"traceutil/trace.go:171","msg":"trace[1674148873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4972; }","duration":"227.740598ms","start":"2026-04-07T13:00:29.905802Z","end":"2026-04-07T13:00:30.133543Z","steps":["trace[1674148873] 'agreement among raft nodes before linearized reading' (duration: 227.621146ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:30.133596Z","caller":"traceutil/trace.go:171","msg":"trace[1479464326] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"282.316762ms","start":"2026-04-07T13:00:29.851096Z","end":"2026-04-07T13:00:30.133413Z","steps":["trace[1479464326] 'process raft request' (duration: 282.120579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:30.133705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.798269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T13:00:30.133775Z","caller":"traceutil/trace.go:171","msg":"trace[1526941631] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4972; }","duration":"227.884469ms","start":"2026-04-07T13:00:29.905862Z","end":"2026-04-07T13:00:30.133747Z","steps":["trace[1526941631] 'agreement among raft nodes before linearized reading' (duration: 227.655056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:30.133762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.062047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-07T13:00:30.13392Z","caller":"traceutil/trace.go:171","msg":"trace[343446605] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4972; }","duration":"102.24367ms","start":"2026-04-07T13:00:30.031629Z","end":"2026-04-07T13:00:30.133873Z","steps":["trace[343446605] 'agreement among raft nodes before linearized reading' (duration: 101.931916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:30.133923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.091562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-07T13:00:30.133992Z","caller":"traceutil/trace.go:171","msg":"trace[482109362] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4972; }","duration":"137.194113ms","start":"2026-04-07T13:00:29.996775Z","end":"2026-04-07T13:00:30.133969Z","steps":["trace[482109362] 'agreement among raft nodes before linearized reading' (duration: 136.964889ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:31.648226Z","caller":"traceutil/trace.go:171","msg":"trace[296372338] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"237.593355ms","start":"2026-04-07T13:00:31.410609Z","end":"2026-04-07T13:00:31.648203Z","steps":["trace[296372338] 'process raft request' (duration: 237.442113ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:31.64823Z","caller":"traceutil/trace.go:171","msg":"trace[1135625969] linearizableReadLoop","detail":"{readStateIndex:5174; appliedIndex:5174; }","duration":"217.441899ms","start":"2026-04-07T13:00:31.43076Z","end":"2026-04-07T13:00:31.648202Z","steps":["trace[1135625969] 'read index received' (duration: 217.425029ms)","trace[1135625969] 'applied index is now lower than readState.Index' (duration: 14.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:31.648578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.386976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:31.648623Z","caller":"traceutil/trace.go:171","msg":"trace[157140343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4988; }","duration":"185.483876ms","start":"2026-04-07T13:00:31.463127Z","end":"2026-04-07T13:00:31.648611Z","steps":["trace[157140343] 'agreement among raft nodes before linearized reading' (duration: 185.388205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:31.648662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.894836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T13:00:31.648994Z","caller":"traceutil/trace.go:171","msg":"trace[43456210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4988; }","duration":"218.271291ms","start":"2026-04-07T13:00:31.43071Z","end":"2026-04-07T13:00:31.648981Z","steps":["trace[43456210] 'agreement among raft nodes before linearized reading' (duration: 217.571451ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:34.397061Z","caller":"traceutil/trace.go:171","msg":"trace[1231706566] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"155.016439ms","start":"2026-04-07T13:00:34.242006Z","end":"2026-04-07T13:00:34.397022Z","steps":["trace[1231706566] 'process raft request' (duration: 150.027529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:34.397148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.223149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:34.397205Z","caller":"traceutil/trace.go:171","msg":"trace[803935833] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:5013; }","duration":"137.321511ms","start":"2026-04-07T13:00:34.259872Z","end":"2026-04-07T13:00:34.397193Z","steps":["trace[803935833] 'agreement among raft nodes before linearized reading' (duration: 137.218089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:34.396896Z","caller":"traceutil/trace.go:171","msg":"trace[183138746] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5199; }","duration":"136.967905ms","start":"2026-04-07T13:00:34.259903Z","end":"2026-04-07T13:00:34.39687Z","steps":["trace[183138746] 'read index received' (duration: 132.059576ms)","trace[183138746] 'applied index is now lower than readState.Index' (duration: 4.907249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:34.397593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.834981ms","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-07T13:00:34.397619Z","caller":"traceutil/trace.go:171","msg":"trace[33315283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5013; }","duration":"128.872301ms","start":"2026-04-07T13:00:34.268738Z","end":"2026-04-07T13:00:34.397611Z","steps":["trace[33315283] 'agreement among raft nodes before linearized reading' (duration: 128.77201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:34.397847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.434067ms","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-07T13:00:34.397868Z","caller":"traceutil/trace.go:171","msg":"trace[582294515] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5013; }","duration":"119.501698ms","start":"2026-04-07T13:00:34.278361Z","end":"2026-04-07T13:00:34.397862Z","steps":["trace[582294515] 'agreement among raft nodes before linearized reading' (duration: 119.465387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:01.840137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.916961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:01:01.840451Z","caller":"traceutil/trace.go:171","msg":"trace[1524455838] linearizableReadLoop","detail":"{readStateIndex:5483; appliedIndex:5482; }","duration":"312.265305ms","start":"2026-04-07T13:01:01.528148Z","end":"2026-04-07T13:01:01.840413Z","steps":["trace[1524455838] 'read index received' (duration: 174.00343ms)","trace[1524455838] 'applied index is now lower than readState.Index' (duration: 138.258935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:01:01.840903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.044367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T13:01:01.840949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.252145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61970"} {"level":"info","ts":"2026-04-07T13:01:01.840997Z","caller":"traceutil/trace.go:171","msg":"trace[1862579978] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5288; }","duration":"312.350347ms","start":"2026-04-07T13:01:01.528632Z","end":"2026-04-07T13:01:01.840982Z","steps":["trace[1862579978] 'agreement among raft nodes before linearized reading' (duration: 312.024353ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:01.840985Z","caller":"traceutil/trace.go:171","msg":"trace[1978124442] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5288; }","duration":"147.151748ms","start":"2026-04-07T13:01:01.693806Z","end":"2026-04-07T13:01:01.840958Z","steps":["trace[1978124442] 'agreement among raft nodes before linearized reading' (duration: 147.039796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:01.84105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:01:01.52862Z","time spent":"312.418498ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":61992,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-04-07T13:01:01.840958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.783412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T13:01:01.841234Z","caller":"traceutil/trace.go:171","msg":"trace[791807543] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5288; }","duration":"313.067956ms","start":"2026-04-07T13:01:01.528111Z","end":"2026-04-07T13:01:01.841179Z","steps":["trace[791807543] 'agreement among raft nodes before linearized reading' (duration: 312.489348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:01.841319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:01:01.528096Z","time spent":"313.208719ms","remote":"127.0.0.1:55798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1146,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-04-07T13:01:01.841128Z","caller":"traceutil/trace.go:171","msg":"trace[1075300938] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"318.028545ms","start":"2026-04-07T13:01:01.523035Z","end":"2026-04-07T13:01:01.841064Z","steps":["trace[1075300938] 'process raft request' (duration: 179.110691ms)","trace[1075300938] 'compare' (duration: 137.692678ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:01:01.842092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:01:01.523014Z","time spent":"318.537672ms","remote":"127.0.0.1:55872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10618,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T13:01:01.843045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.775818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-07T13:01:01.843131Z","caller":"traceutil/trace.go:171","msg":"trace[1376089039] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5288; }","duration":"203.603841ms","start":"2026-04-07T13:01:01.639506Z","end":"2026-04-07T13:01:01.84311Z","steps":["trace[1376089039] 'agreement among raft nodes before linearized reading' (duration: 201.542802ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:03.66174Z","caller":"traceutil/trace.go:171","msg":"trace[1342053721] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"326.518557ms","start":"2026-04-07T13:01:03.335185Z","end":"2026-04-07T13:01:03.661704Z","steps":["trace[1342053721] 'read index received' (duration: 326.192093ms)","trace[1342053721] 'applied index is now lower than readState.Index' (duration: 325.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:01:03.661821Z","caller":"traceutil/trace.go:171","msg":"trace[663735123] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"404.592668ms","start":"2026-04-07T13:01:03.257182Z","end":"2026-04-07T13:01:03.661775Z","steps":["trace[663735123] 'process raft request' (duration: 404.281654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:03.662021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.790891ms","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-07T13:01:03.662138Z","caller":"traceutil/trace.go:171","msg":"trace[648595374] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5332; }","duration":"326.948473ms","start":"2026-04-07T13:01:03.335166Z","end":"2026-04-07T13:01:03.662114Z","steps":["trace[648595374] 'agreement among raft nodes before linearized reading' (duration: 326.7158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:03.662075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.867484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:01:03.662219Z","caller":"traceutil/trace.go:171","msg":"trace[1976588141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5332; }","duration":"197.057176ms","start":"2026-04-07T13:01:03.465136Z","end":"2026-04-07T13:01:03.662194Z","steps":["trace[1976588141] 'agreement among raft nodes before linearized reading' (duration: 196.826353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:03.662213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:01:03.335051Z","time spent":"327.145216ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"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-07T13:01:03.6624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.722004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T13:01:03.662486Z","caller":"traceutil/trace.go:171","msg":"trace[1379013230] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:5332; }","duration":"228.849316ms","start":"2026-04-07T13:01:03.433616Z","end":"2026-04-07T13:01:03.662465Z","steps":["trace[1379013230] 'agreement among raft nodes before linearized reading' (duration: 228.341959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:03.662946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:01:03.257164Z","time spent":"404.958072ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12124,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:01:20.332705Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2850} {"level":"info","ts":"2026-04-07T13:01:20.428426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2850,"took":"94.196579ms","hash":221500361,"current-db-size-bytes":52973568,"current-db-size":"53 MB","current-db-size-in-use-bytes":28155904,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T13:01:20.428505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":221500361,"revision":2850,"compact-revision":-1} {"level":"info","ts":"2026-04-07T13:01:56.725285Z","caller":"traceutil/trace.go:171","msg":"trace[1830136758] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"116.425805ms","start":"2026-04-07T13:01:56.608792Z","end":"2026-04-07T13:01:56.725218Z","steps":["trace[1830136758] 'process raft request' (duration: 116.167372ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:11.524483Z","caller":"traceutil/trace.go:171","msg":"trace[1638514348] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"118.428002ms","start":"2026-04-07T13:02:11.406011Z","end":"2026-04-07T13:02:11.524439Z","steps":["trace[1638514348] 'process raft request' (duration: 117.906974ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:59.625419Z","caller":"traceutil/trace.go:171","msg":"trace[1453247954] linearizableReadLoop","detail":"{readStateIndex:7190; appliedIndex:7189; }","duration":"101.112396ms","start":"2026-04-07T13:04:59.52428Z","end":"2026-04-07T13:04:59.625392Z","steps":["trace[1453247954] 'read index received' (duration: 100.88774ms)","trace[1453247954] 'applied index is now lower than readState.Index' (duration: 223.776µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:04:59.625696Z","caller":"traceutil/trace.go:171","msg":"trace[1785542929] transaction","detail":"{read_only:false; response_revision:6935; number_of_response:1; }","duration":"102.583604ms","start":"2026-04-07T13:04:59.523102Z","end":"2026-04-07T13:04:59.625686Z","steps":["trace[1785542929] 'process raft request' (duration: 102.135593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:59.625945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.639239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T13:04:59.625982Z","caller":"traceutil/trace.go:171","msg":"trace[2109437143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6935; }","duration":"101.722471ms","start":"2026-04-07T13:04:59.524249Z","end":"2026-04-07T13:04:59.625972Z","steps":["trace[2109437143] 'agreement among raft nodes before linearized reading' (duration: 101.590797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:59.626091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.016688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:04:59.626117Z","caller":"traceutil/trace.go:171","msg":"trace[913606493] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6935; }","duration":"100.059138ms","start":"2026-04-07T13:04:59.526052Z","end":"2026-04-07T13:04:59.626111Z","steps":["trace[913606493] 'agreement among raft nodes before linearized reading' (duration: 100.019497ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:20.339342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5423} {"level":"info","ts":"2026-04-07T13:06:20.420631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5423,"took":"79.434577ms","hash":917455105,"current-db-size-bytes":52973568,"current-db-size":"53 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T13:06:20.420722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":917455105,"revision":5423,"compact-revision":2850}