{"level":"warn","ts":"2026-04-20T14:14:58.259743Z","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-20T14:14:58.259854Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-20T14:14:58.259928Z","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-20T14:14:58.259942Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-20T14:14:58.259962Z","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-20T14:14:58.260671Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-04-20T14:14:58.260758Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-20T14:14:58.267412Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.462717ms"} {"level":"info","ts":"2026-04-20T14:14:58.274335Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-04-20T14:14:58.274445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:14:58.274475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-04-20T14:14:58.274495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:14:58.274509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-04-20T14:14:58.274559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-04-20T14:14:58.279998Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:14:58.281655Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:14:58.282723Z","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-20T14:14:58.286827Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:14:58.287163Z","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-20T14:14:58.287311Z","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-20T14:14:58.287328Z","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-20T14:14:58.287289Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:14:58.292891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-04-20T14:14:58.293086Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-20T14:14:58.294114Z","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-20T14:14:58.294203Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-20T14:14:58.294334Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-20T14:14:58.294599Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:14:58.294652Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:14:58.575059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:14:58.575218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:14:58.575285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-04-20T14:14:58.57531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:14:58.575327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-20T14:14:58.575346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-04-20T14:14:58.575366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-20T14:14:58.576498Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:14:58.577437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:14:58.577423Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:14:58.577865Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:14:58.578113Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:14:58.578237Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:14:58.579064Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:14:58.57921Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:14:58.579249Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:14:58.581448Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-04-20T14:14:58.582067Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T14:15:29.070904Z","caller":"traceutil/trace.go:171","msg":"trace[102793172] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"108.989544ms","start":"2026-04-20T14:15:28.961887Z","end":"2026-04-20T14:15:29.070877Z","steps":["trace[102793172] 'process raft request' (duration: 108.75968ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:15:29.078749Z","caller":"traceutil/trace.go:171","msg":"trace[449746043] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"112.233055ms","start":"2026-04-20T14:15:28.966471Z","end":"2026-04-20T14:15:29.078704Z","steps":["trace[449746043] 'process raft request' (duration: 111.896229ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:15:29.07931Z","caller":"traceutil/trace.go:171","msg":"trace[1461565648] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"111.527713ms","start":"2026-04-20T14:15:28.967741Z","end":"2026-04-20T14:15:29.079268Z","steps":["trace[1461565648] 'process raft request' (duration: 110.831499ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:15:30.734892Z","caller":"traceutil/trace.go:171","msg":"trace[1455567021] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"143.189642ms","start":"2026-04-20T14:15:30.591683Z","end":"2026-04-20T14:15:30.734873Z","steps":["trace[1455567021] 'read index received' (duration: 53.177267ms)","trace[1455567021] 'applied index is now lower than readState.Index' (duration: 90.011495ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:15:30.735052Z","caller":"traceutil/trace.go:171","msg":"trace[662538472] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"148.908221ms","start":"2026-04-20T14:15:30.586125Z","end":"2026-04-20T14:15:30.735033Z","steps":["trace[662538472] 'process raft request' (duration: 58.819255ms)","trace[662538472] 'compare' (duration: 89.73461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:15:30.735297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.579269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-20T14:15:30.735423Z","caller":"traceutil/trace.go:171","msg":"trace[1718902520] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:549; }","duration":"143.757673ms","start":"2026-04-20T14:15:30.591645Z","end":"2026-04-20T14:15:30.735402Z","steps":["trace[1718902520] 'agreement among raft nodes before linearized reading' (duration: 143.418917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:15:34.843432Z","caller":"traceutil/trace.go:171","msg":"trace[85689865] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"266.466683ms","start":"2026-04-20T14:15:34.576942Z","end":"2026-04-20T14:15:34.843409Z","steps":["trace[85689865] 'read index received' (duration: 264.428096ms)","trace[85689865] 'applied index is now lower than readState.Index' (duration: 2.037437ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:15:34.843561Z","caller":"traceutil/trace.go:171","msg":"trace[342618275] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"319.437164ms","start":"2026-04-20T14:15:34.524113Z","end":"2026-04-20T14:15:34.84355Z","steps":["trace[342618275] 'process raft request' (duration: 317.526519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:15:34.849972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:15:34.524086Z","time spent":"319.506055ms","remote":"127.0.0.1:47458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:15:34.859017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.068445ms","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-20T14:15:34.859155Z","caller":"traceutil/trace.go:171","msg":"trace[419588398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"282.257369ms","start":"2026-04-20T14:15:34.576885Z","end":"2026-04-20T14:15:34.859143Z","steps":["trace[419588398] 'agreement among raft nodes before linearized reading' (duration: 272.71013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:15:34.859252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.71119ms","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-20T14:15:34.859293Z","caller":"traceutil/trace.go:171","msg":"trace[237128525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"103.784181ms","start":"2026-04-20T14:15:34.755499Z","end":"2026-04-20T14:15:34.859283Z","steps":["trace[237128525] 'agreement among raft nodes before linearized reading' (duration: 94.120369ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:15:35.663624Z","caller":"traceutil/trace.go:171","msg":"trace[979116002] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"286.858729ms","start":"2026-04-20T14:15:35.376724Z","end":"2026-04-20T14:15:35.663583Z","steps":["trace[979116002] 'process raft request' (duration: 285.424063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:15:36.513647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.644574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:15:36.513751Z","caller":"traceutil/trace.go:171","msg":"trace[451419008] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"376.338168ms","start":"2026-04-20T14:15:36.137397Z","end":"2026-04-20T14:15:36.513735Z","steps":["trace[451419008] 'read index received' (duration: 78.202744ms)","trace[451419008] 'applied index is now lower than readState.Index' (duration: 298.134214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:15:36.51394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.552252ms","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-20T14:15:36.513975Z","caller":"traceutil/trace.go:171","msg":"trace[1059100079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"376.624713ms","start":"2026-04-20T14:15:36.137341Z","end":"2026-04-20T14:15:36.513966Z","steps":["trace[1059100079] 'agreement among raft nodes before linearized reading' (duration: 376.4729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:15:36.51401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:15:36.13732Z","time spent":"376.682475ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T14:15:36.51424Z","caller":"traceutil/trace.go:171","msg":"trace[1866118606] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"666.040861ms","start":"2026-04-20T14:15:35.848158Z","end":"2026-04-20T14:15:36.514198Z","steps":["trace[1866118606] 'process raft request' (duration: 367.50586ms)","trace[1866118606] 'compare' (duration: 297.525222ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:15:36.514667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:15:35.848125Z","time spent":"666.190864ms","remote":"127.0.0.1:47572","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-20T14:15:36.524982Z","caller":"traceutil/trace.go:171","msg":"trace[431758429] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"286.001478ms","start":"2026-04-20T14:15:36.238959Z","end":"2026-04-20T14:15:36.524961Z","steps":["trace[431758429] 'process raft request' (duration: 280.419285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:15:52.010142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.599355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:15:52.010657Z","caller":"traceutil/trace.go:171","msg":"trace[363870476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"141.207995ms","start":"2026-04-20T14:15:51.869425Z","end":"2026-04-20T14:15:52.010633Z","steps":["trace[363870476] 'range keys from in-memory index tree' (duration: 140.449593ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:15:57.260053Z","caller":"traceutil/trace.go:171","msg":"trace[1541729690] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"120.193057ms","start":"2026-04-20T14:15:57.139823Z","end":"2026-04-20T14:15:57.260016Z","steps":["trace[1541729690] 'process raft request' (duration: 84.199317ms)","trace[1541729690] 'compare' (duration: 35.792187ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:15:58.891111Z","caller":"traceutil/trace.go:171","msg":"trace[1996014541] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"103.03163ms","start":"2026-04-20T14:15:58.788046Z","end":"2026-04-20T14:15:58.891078Z","steps":["trace[1996014541] 'process raft request' (duration: 77.003747ms)","trace[1996014541] 'compare' (duration: 25.910571ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:17:04.179593Z","caller":"traceutil/trace.go:171","msg":"trace[488046319] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"110.027639ms","start":"2026-04-20T14:17:04.06952Z","end":"2026-04-20T14:17:04.179548Z","steps":["trace[488046319] 'process raft request' (duration: 109.864645ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:11.370054Z","caller":"traceutil/trace.go:171","msg":"trace[2121336503] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"140.012177ms","start":"2026-04-20T14:17:11.230017Z","end":"2026-04-20T14:17:11.370029Z","steps":["trace[2121336503] 'process raft request' (duration: 139.784782ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:11.497719Z","caller":"traceutil/trace.go:171","msg":"trace[38172812] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1895; }","duration":"114.747201ms","start":"2026-04-20T14:17:11.382945Z","end":"2026-04-20T14:17:11.497692Z","steps":["trace[38172812] 'read index received' (duration: 30.290752ms)","trace[38172812] 'applied index is now lower than readState.Index' (duration: 84.455429ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:17:11.498074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.097258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d8e65bde-8dd5-454a-8f37-fc7f30193135\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T14:17:11.498208Z","caller":"traceutil/trace.go:171","msg":"trace[173780218] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d8e65bde-8dd5-454a-8f37-fc7f30193135; range_end:; response_count:1; response_revision:1825; }","duration":"115.253442ms","start":"2026-04-20T14:17:11.382915Z","end":"2026-04-20T14:17:11.498169Z","steps":["trace[173780218] 'agreement among raft nodes before linearized reading' (duration: 114.958186ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:11.498128Z","caller":"traceutil/trace.go:171","msg":"trace[573061255] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"122.022457ms","start":"2026-04-20T14:17:11.37607Z","end":"2026-04-20T14:17:11.498093Z","steps":["trace[573061255] 'process raft request' (duration: 37.21512ms)","trace[573061255] 'compare' (duration: 84.017959ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:17:11.812219Z","caller":"traceutil/trace.go:171","msg":"trace[1752024057] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"194.926782ms","start":"2026-04-20T14:17:11.617242Z","end":"2026-04-20T14:17:11.812169Z","steps":["trace[1752024057] 'read index received' (duration: 95.925172ms)","trace[1752024057] 'applied index is now lower than readState.Index' (duration: 99.0001ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:17:11.812271Z","caller":"traceutil/trace.go:171","msg":"trace[1614885392] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"307.070912ms","start":"2026-04-20T14:17:11.505145Z","end":"2026-04-20T14:17:11.812216Z","steps":["trace[1614885392] 'process raft request' (duration: 208.023731ms)","trace[1614885392] 'compare' (duration: 98.732544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:17:11.812483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:11.50512Z","time spent":"307.247617ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:17:11.813257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.990806ms","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-20T14:17:11.813367Z","caller":"traceutil/trace.go:171","msg":"trace[467071657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1826; }","duration":"196.1488ms","start":"2026-04-20T14:17:11.617195Z","end":"2026-04-20T14:17:11.813344Z","steps":["trace[467071657] 'agreement among raft nodes before linearized reading' (duration: 195.085146ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:12.067243Z","caller":"traceutil/trace.go:171","msg":"trace[835525968] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"279.368666ms","start":"2026-04-20T14:17:11.787838Z","end":"2026-04-20T14:17:12.067207Z","steps":["trace[835525968] 'process raft request' (duration: 262.313117ms)","trace[835525968] 'compare' (duration: 16.847934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:17:12.06776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.346303ms","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-20T14:17:12.067863Z","caller":"traceutil/trace.go:171","msg":"trace[364302167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1827; }","duration":"395.505407ms","start":"2026-04-20T14:17:11.672333Z","end":"2026-04-20T14:17:12.067838Z","steps":["trace[364302167] 'agreement among raft nodes before linearized reading' (duration: 394.882213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:12.067923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:11.672312Z","time spent":"395.599619ms","remote":"127.0.0.1:47666","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-20T14:17:12.067978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.9895ms","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-20T14:17:12.068016Z","caller":"traceutil/trace.go:171","msg":"trace[2104764129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1827; }","duration":"450.065003ms","start":"2026-04-20T14:17:11.617941Z","end":"2026-04-20T14:17:12.068006Z","steps":["trace[2104764129] 'agreement among raft nodes before linearized reading' (duration: 449.910569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:12.068048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:11.617932Z","time spent":"450.108933ms","remote":"127.0.0.1:47666","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-20T14:17:12.067811Z","caller":"traceutil/trace.go:171","msg":"trace[1474725078] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1897; }","duration":"254.862835ms","start":"2026-04-20T14:17:11.812294Z","end":"2026-04-20T14:17:12.067156Z","steps":["trace[1474725078] 'read index received' (duration: 237.872739ms)","trace[1474725078] 'applied index is now lower than readState.Index' (duration: 16.989116ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:17:12.074605Z","caller":"traceutil/trace.go:171","msg":"trace[1858533040] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"255.731116ms","start":"2026-04-20T14:17:11.818838Z","end":"2026-04-20T14:17:12.074569Z","steps":["trace[1858533040] 'process raft request' (duration: 255.652034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:12.074643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.695368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:17:12.074911Z","caller":"traceutil/trace.go:171","msg":"trace[845334885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1829; }","duration":"208.026426ms","start":"2026-04-20T14:17:11.866864Z","end":"2026-04-20T14:17:12.074891Z","steps":["trace[845334885] 'agreement among raft nodes before linearized reading' (duration: 207.677068ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:12.074995Z","caller":"traceutil/trace.go:171","msg":"trace[1991605894] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"256.149675ms","start":"2026-04-20T14:17:11.818836Z","end":"2026-04-20T14:17:12.074985Z","steps":["trace[1991605894] 'process raft request' (duration: 255.508831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:12.075204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.018324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:17:12.075249Z","caller":"traceutil/trace.go:171","msg":"trace[807510924] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1829; }","duration":"155.097827ms","start":"2026-04-20T14:17:11.920141Z","end":"2026-04-20T14:17:12.075239Z","steps":["trace[807510924] 'agreement among raft nodes before linearized reading' (duration: 155.015295ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:18.445532Z","caller":"traceutil/trace.go:171","msg":"trace[1225788417] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"114.147324ms","start":"2026-04-20T14:17:18.331326Z","end":"2026-04-20T14:17:18.445474Z","steps":["trace[1225788417] 'process raft request' (duration: 113.93231ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:39.163095Z","caller":"traceutil/trace.go:171","msg":"trace[175871390] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"106.846163ms","start":"2026-04-20T14:17:39.056228Z","end":"2026-04-20T14:17:39.163074Z","steps":["trace[175871390] 'process raft request' (duration: 106.7103ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:39.308212Z","caller":"traceutil/trace.go:171","msg":"trace[584764839] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"107.236721ms","start":"2026-04-20T14:17:39.200934Z","end":"2026-04-20T14:17:39.308171Z","steps":["trace[584764839] 'process raft request' (duration: 107.084428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:45.84383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.775156ms","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-20T14:17:45.843907Z","caller":"traceutil/trace.go:171","msg":"trace[1963974011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2063; }","duration":"138.902789ms","start":"2026-04-20T14:17:45.704987Z","end":"2026-04-20T14:17:45.84389Z","steps":["trace[1963974011] 'range keys from in-memory index tree' (duration: 138.602133ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:46.535247Z","caller":"traceutil/trace.go:171","msg":"trace[969545058] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"320.945912ms","start":"2026-04-20T14:17:46.21428Z","end":"2026-04-20T14:17:46.535226Z","steps":["trace[969545058] 'process raft request' (duration: 296.281885ms)","trace[969545058] 'compare' (duration: 24.417292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:17:46.535414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.214244Z","time spent":"321.063345ms","remote":"127.0.0.1:47666","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-20T14:17:46.535493Z","caller":"traceutil/trace.go:171","msg":"trace[1098186434] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2142; }","duration":"294.074898ms","start":"2026-04-20T14:17:46.24138Z","end":"2026-04-20T14:17:46.535455Z","steps":["trace[1098186434] 'read index received' (duration: 14.009584ms)","trace[1098186434] 'applied index is now lower than readState.Index' (duration: 280.063944ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:17:46.535644Z","caller":"traceutil/trace.go:171","msg":"trace[633826268] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"304.66458ms","start":"2026-04-20T14:17:46.230953Z","end":"2026-04-20T14:17:46.535618Z","steps":["trace[633826268] 'process raft request' (duration: 304.211291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:46.535732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.230919Z","time spent":"304.780193ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:17:46.53575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.360144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:17:46.536502Z","caller":"traceutil/trace.go:171","msg":"trace[155936657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2067; }","duration":"295.16578ms","start":"2026-04-20T14:17:46.241325Z","end":"2026-04-20T14:17:46.53649Z","steps":["trace[155936657] 'agreement among raft nodes before linearized reading' (duration: 294.300283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:46.535802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.126949ms","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-20T14:17:46.536626Z","caller":"traceutil/trace.go:171","msg":"trace[1037785566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2067; }","duration":"284.975587ms","start":"2026-04-20T14:17:46.251643Z","end":"2026-04-20T14:17:46.536619Z","steps":["trace[1037785566] 'agreement among raft nodes before linearized reading' (duration: 284.072098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:46.535986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.864674ms","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-20T14:17:46.536697Z","caller":"traceutil/trace.go:171","msg":"trace[251937753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2067; }","duration":"284.59462ms","start":"2026-04-20T14:17:46.252097Z","end":"2026-04-20T14:17:46.536691Z","steps":["trace[251937753] 'agreement among raft nodes before linearized reading' (duration: 283.833153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:47.054966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225630558712462,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:17:47.386203Z","caller":"traceutil/trace.go:171","msg":"trace[2143181518] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"845.489118ms","start":"2026-04-20T14:17:46.540682Z","end":"2026-04-20T14:17:47.386171Z","steps":["trace[2143181518] 'process raft request' (duration: 801.824764ms)","trace[2143181518] 'compare' (duration: 43.317537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:17:47.386357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.540663Z","time spent":"845.611461ms","remote":"127.0.0.1:47666","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-20T14:17:47.386386Z","caller":"traceutil/trace.go:171","msg":"trace[2093398571] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"845.469958ms","start":"2026-04-20T14:17:46.540892Z","end":"2026-04-20T14:17:47.386362Z","steps":["trace[2093398571] 'process raft request' (duration: 845.347406ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:47.386387Z","caller":"traceutil/trace.go:171","msg":"trace[1287549328] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"845.672812ms","start":"2026-04-20T14:17:46.540682Z","end":"2026-04-20T14:17:47.386355Z","steps":["trace[1287549328] 'process raft request' (duration: 845.409687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:47.386632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.540848Z","time spent":"845.634052ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:17:47.386675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.54067Z","time spent":"845.954718ms","remote":"127.0.0.1:47666","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-20T14:17:47.386883Z","caller":"traceutil/trace.go:171","msg":"trace[1717311025] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"845.622171ms","start":"2026-04-20T14:17:46.541234Z","end":"2026-04-20T14:17:47.386857Z","steps":["trace[1717311025] 'process raft request' (duration: 845.07231ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:47.387135Z","caller":"traceutil/trace.go:171","msg":"trace[1534659302] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"829.465682ms","start":"2026-04-20T14:17:46.557645Z","end":"2026-04-20T14:17:47.387111Z","steps":["trace[1534659302] 'process raft request' (duration: 829.092104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:47.387278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.557628Z","time spent":"829.579265ms","remote":"127.0.0.1:47666","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-20T14:17:47.3873Z","caller":"traceutil/trace.go:171","msg":"trace[761340439] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2145; }","duration":"832.931694ms","start":"2026-04-20T14:17:46.55431Z","end":"2026-04-20T14:17:47.387241Z","steps":["trace[761340439] 'read index received' (duration: 788.186118ms)","trace[761340439] 'applied index is now lower than readState.Index' (duration: 44.740776ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:17:47.387411Z","caller":"traceutil/trace.go:171","msg":"trace[1704738772] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"141.124095ms","start":"2026-04-20T14:17:47.246275Z","end":"2026-04-20T14:17:47.387399Z","steps":["trace[1704738772] 'process raft request' (duration: 140.755327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:47.387174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.541222Z","time spent":"845.756754ms","remote":"127.0.0.1:47666","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-20T14:17:47.387547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.405908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:17:47.387591Z","caller":"traceutil/trace.go:171","msg":"trace[890496563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2073; }","duration":"518.480099ms","start":"2026-04-20T14:17:46.869099Z","end":"2026-04-20T14:17:47.387579Z","steps":["trace[890496563] 'agreement among raft nodes before linearized reading' (duration: 518.417238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:47.387626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.86908Z","time spent":"518.539011ms","remote":"127.0.0.1:47398","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-20T14:17:47.38748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.164189ms","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-20T14:17:47.388582Z","caller":"traceutil/trace.go:171","msg":"trace[63131496] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2073; }","duration":"834.287984ms","start":"2026-04-20T14:17:46.554272Z","end":"2026-04-20T14:17:47.38856Z","steps":["trace[63131496] 'agreement among raft nodes before linearized reading' (duration: 833.146959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:47.387543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.625382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:17:47.388647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.554255Z","time spent":"834.374965ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:17:47.388702Z","caller":"traceutil/trace.go:171","msg":"trace[540615076] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2073; }","duration":"611.785677ms","start":"2026-04-20T14:17:46.776851Z","end":"2026-04-20T14:17:47.388636Z","steps":["trace[540615076] 'agreement among raft nodes before linearized reading' (duration: 610.632762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:47.388927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:46.776828Z","time spent":"612.039933ms","remote":"127.0.0.1:45182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:17:47.717423Z","caller":"traceutil/trace.go:171","msg":"trace[1735231647] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2151; }","duration":"173.251027ms","start":"2026-04-20T14:17:47.544131Z","end":"2026-04-20T14:17:47.717382Z","steps":["trace[1735231647] 'read index received' (duration: 170.678843ms)","trace[1735231647] 'applied index is now lower than readState.Index' (duration: 2.570554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:17:47.71794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.748597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T14:17:47.718049Z","caller":"traceutil/trace.go:171","msg":"trace[1870234274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2073; }","duration":"173.945721ms","start":"2026-04-20T14:17:47.544087Z","end":"2026-04-20T14:17:47.718033Z","steps":["trace[1870234274] 'agreement among raft nodes before linearized reading' (duration: 173.551873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:27.066961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.078309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:18:27.067064Z","caller":"traceutil/trace.go:171","msg":"trace[712917446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2290; }","duration":"197.347685ms","start":"2026-04-20T14:18:26.869685Z","end":"2026-04-20T14:18:27.067032Z","steps":["trace[712917446] 'range keys from in-memory index tree' (duration: 196.949697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:27.067683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.325969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:18:27.067947Z","caller":"traceutil/trace.go:171","msg":"trace[1608858322] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"151.436606ms","start":"2026-04-20T14:18:26.916452Z","end":"2026-04-20T14:18:27.067888Z","steps":["trace[1608858322] 'process raft request' (duration: 23.822441ms)","trace[1608858322] 'compare' (duration: 126.509203ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:18:44.323032Z","caller":"traceutil/trace.go:171","msg":"trace[8613791] linearizableReadLoop","detail":"{readStateIndex:2512; appliedIndex:2511; }","duration":"158.80673ms","start":"2026-04-20T14:18:44.164208Z","end":"2026-04-20T14:18:44.323014Z","steps":["trace[8613791] 'read index received' (duration: 157.437074ms)","trace[8613791] 'applied index is now lower than readState.Index' (duration: 1.368746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:18:44.323954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.746258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-20T14:18:44.324011Z","caller":"traceutil/trace.go:171","msg":"trace[91537912] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2420; }","duration":"159.853051ms","start":"2026-04-20T14:18:44.164148Z","end":"2026-04-20T14:18:44.324001Z","steps":["trace[91537912] 'agreement among raft nodes before linearized reading' (duration: 158.984583ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:44.324115Z","caller":"traceutil/trace.go:171","msg":"trace[1706999275] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"185.799786ms","start":"2026-04-20T14:18:44.138166Z","end":"2026-04-20T14:18:44.323966Z","steps":["trace[1706999275] 'process raft request' (duration: 183.608223ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:54.401569Z","caller":"traceutil/trace.go:171","msg":"trace[1955980538] linearizableReadLoop","detail":"{readStateIndex:2627; appliedIndex:2626; }","duration":"201.504382ms","start":"2026-04-20T14:18:54.200048Z","end":"2026-04-20T14:18:54.401552Z","steps":["trace[1955980538] 'read index received' (duration: 201.336788ms)","trace[1955980538] 'applied index is now lower than readState.Index' (duration: 166.444µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:18:54.401838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.772637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-04-20T14:18:54.40187Z","caller":"traceutil/trace.go:171","msg":"trace[1375427293] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2533; }","duration":"201.833297ms","start":"2026-04-20T14:18:54.200029Z","end":"2026-04-20T14:18:54.401862Z","steps":["trace[1375427293] 'agreement among raft nodes before linearized reading' (duration: 201.661104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:54.401788Z","caller":"traceutil/trace.go:171","msg":"trace[1333376111] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"202.918748ms","start":"2026-04-20T14:18:54.198784Z","end":"2026-04-20T14:18:54.401703Z","steps":["trace[1333376111] 'process raft request' (duration: 202.586802ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:27.311542Z","caller":"traceutil/trace.go:171","msg":"trace[1309477910] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"226.78401ms","start":"2026-04-20T14:19:27.084733Z","end":"2026-04-20T14:19:27.311517Z","steps":["trace[1309477910] 'process raft request' (duration: 226.560046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.743507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.753322ms","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-20T14:19:32.743531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.838495ms","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-20T14:19:32.743583Z","caller":"traceutil/trace.go:171","msg":"trace[1209926491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2743; }","duration":"150.872765ms","start":"2026-04-20T14:19:32.592695Z","end":"2026-04-20T14:19:32.743567Z","steps":["trace[1209926491] 'range keys from in-memory index tree' (duration: 150.576249ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.743612Z","caller":"traceutil/trace.go:171","msg":"trace[698698045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2743; }","duration":"149.972637ms","start":"2026-04-20T14:19:32.59362Z","end":"2026-04-20T14:19:32.743593Z","steps":["trace[698698045] 'range keys from in-memory index tree' (duration: 149.60514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.743816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.890682ms","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-20T14:19:32.743888Z","caller":"traceutil/trace.go:171","msg":"trace[694753526] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2743; }","duration":"163.141417ms","start":"2026-04-20T14:19:32.580731Z","end":"2026-04-20T14:19:32.743873Z","steps":["trace[694753526] 'count revisions from in-memory index tree' (duration: 162.79785ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:03.086768Z","caller":"traceutil/trace.go:171","msg":"trace[1416420871] linearizableReadLoop","detail":"{readStateIndex:3012; appliedIndex:3011; }","duration":"140.544234ms","start":"2026-04-20T14:20:02.946194Z","end":"2026-04-20T14:20:03.086739Z","steps":["trace[1416420871] 'read index received' (duration: 140.384421ms)","trace[1416420871] 'applied index is now lower than readState.Index' (duration: 158.163µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:03.086902Z","caller":"traceutil/trace.go:171","msg":"trace[467624825] transaction","detail":"{read_only:false; response_revision:2903; number_of_response:1; }","duration":"194.509246ms","start":"2026-04-20T14:20:02.892362Z","end":"2026-04-20T14:20:03.086871Z","steps":["trace[467624825] 'process raft request' (duration: 194.20195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:03.087196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.82555ms","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-20T14:20:03.087243Z","caller":"traceutil/trace.go:171","msg":"trace[136147209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2903; }","duration":"139.916943ms","start":"2026-04-20T14:20:02.947314Z","end":"2026-04-20T14:20:03.087231Z","steps":["trace[136147209] 'agreement among raft nodes before linearized reading' (duration: 139.7735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:03.087313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.126176ms","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-20T14:20:03.087344Z","caller":"traceutil/trace.go:171","msg":"trace[1430570939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2903; }","duration":"141.193856ms","start":"2026-04-20T14:20:02.946143Z","end":"2026-04-20T14:20:03.087336Z","steps":["trace[1430570939] 'agreement among raft nodes before linearized reading' (duration: 140.82205ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:18.446441Z","caller":"traceutil/trace.go:171","msg":"trace[1414630871] linearizableReadLoop","detail":"{readStateIndex:3095; appliedIndex:3094; }","duration":"116.043818ms","start":"2026-04-20T14:20:18.330369Z","end":"2026-04-20T14:20:18.446413Z","steps":["trace[1414630871] 'read index received' (duration: 113.791006ms)","trace[1414630871] 'applied index is now lower than readState.Index' (duration: 2.251632ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:18.446485Z","caller":"traceutil/trace.go:171","msg":"trace[1532843289] transaction","detail":"{read_only:false; response_revision:2982; number_of_response:1; }","duration":"166.329977ms","start":"2026-04-20T14:20:18.280131Z","end":"2026-04-20T14:20:18.446461Z","steps":["trace[1532843289] 'process raft request' (duration: 163.963742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:18.447258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.322913ms","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-20T14:20:18.447334Z","caller":"traceutil/trace.go:171","msg":"trace[1093307701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2982; }","duration":"116.988976ms","start":"2026-04-20T14:20:18.330326Z","end":"2026-04-20T14:20:18.447315Z","steps":["trace[1093307701] 'agreement among raft nodes before linearized reading' (duration: 116.246272ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:47.674541Z","caller":"traceutil/trace.go:171","msg":"trace[674749029] transaction","detail":"{read_only:false; response_revision:3135; number_of_response:1; }","duration":"154.680815ms","start":"2026-04-20T14:20:47.519819Z","end":"2026-04-20T14:20:47.6745Z","steps":["trace[674749029] 'process raft request' (duration: 154.442349ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:47.725302Z","caller":"traceutil/trace.go:171","msg":"trace[1259800872] transaction","detail":"{read_only:false; response_revision:3136; number_of_response:1; }","duration":"203.109369ms","start":"2026-04-20T14:20:47.522161Z","end":"2026-04-20T14:20:47.725271Z","steps":["trace[1259800872] 'process raft request' (duration: 177.594874ms)","trace[1259800872] 'compare' (duration: 24.701575ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:47.725437Z","caller":"traceutil/trace.go:171","msg":"trace[1362936274] linearizableReadLoop","detail":"{readStateIndex:3255; appliedIndex:3253; }","duration":"187.66565ms","start":"2026-04-20T14:20:47.537732Z","end":"2026-04-20T14:20:47.725398Z","steps":["trace[1362936274] 'read index received' (duration: 136.562396ms)","trace[1362936274] 'applied index is now lower than readState.Index' (duration: 51.100573ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:20:47.725806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.051269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-20T14:20:47.725857Z","caller":"traceutil/trace.go:171","msg":"trace[388614139] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3137; }","duration":"188.147612ms","start":"2026-04-20T14:20:47.537692Z","end":"2026-04-20T14:20:47.72584Z","steps":["trace[388614139] 'agreement among raft nodes before linearized reading' (duration: 187.868395ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:49.845007Z","caller":"traceutil/trace.go:171","msg":"trace[1072241719] transaction","detail":"{read_only:false; response_revision:3146; number_of_response:1; }","duration":"113.309588ms","start":"2026-04-20T14:20:49.731659Z","end":"2026-04-20T14:20:49.844969Z","steps":["trace[1072241719] 'process raft request' (duration: 113.083083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:50.059776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.214879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:20:50.059967Z","caller":"traceutil/trace.go:171","msg":"trace[458656559] linearizableReadLoop","detail":"{readStateIndex:3266; appliedIndex:3265; }","duration":"185.390454ms","start":"2026-04-20T14:20:49.87455Z","end":"2026-04-20T14:20:50.059941Z","steps":["trace[458656559] 'read index received' (duration: 47.692µs)","trace[458656559] 'applied index is now lower than readState.Index' (duration: 185.339952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:20:50.060183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.6389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:20:50.060228Z","caller":"traceutil/trace.go:171","msg":"trace[1300150109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3147; }","duration":"185.720762ms","start":"2026-04-20T14:20:49.874493Z","end":"2026-04-20T14:20:50.060214Z","steps":["trace[1300150109] 'agreement among raft nodes before linearized reading' (duration: 185.551308ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:50.060646Z","caller":"traceutil/trace.go:171","msg":"trace[461509257] transaction","detail":"{read_only:false; response_revision:3147; number_of_response:1; }","duration":"326.012399ms","start":"2026-04-20T14:20:49.734572Z","end":"2026-04-20T14:20:50.060584Z","steps":["trace[461509257] 'process raft request' (duration: 139.784335ms)","trace[461509257] 'compare' (duration: 184.982052ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:20:50.061003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:49.734544Z","time spent":"326.303347ms","remote":"127.0.0.1:47666","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-20T14:20:50.311458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.694256ms","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-20T14:20:50.311594Z","caller":"traceutil/trace.go:171","msg":"trace[370761231] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3147; }","duration":"110.899421ms","start":"2026-04-20T14:20:50.200665Z","end":"2026-04-20T14:20:50.311565Z","steps":["trace[370761231] 'range keys from in-memory index tree' (duration: 110.407419ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:50.57384Z","caller":"traceutil/trace.go:171","msg":"trace[541139889] transaction","detail":"{read_only:false; response_revision:3148; number_of_response:1; }","duration":"103.547326ms","start":"2026-04-20T14:20:50.470258Z","end":"2026-04-20T14:20:50.573805Z","steps":["trace[541139889] 'process raft request' (duration: 103.328641ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:50.605748Z","caller":"traceutil/trace.go:171","msg":"trace[2052056419] transaction","detail":"{read_only:false; response_revision:3149; number_of_response:1; }","duration":"120.340429ms","start":"2026-04-20T14:20:50.485378Z","end":"2026-04-20T14:20:50.605719Z","steps":["trace[2052056419] 'process raft request' (duration: 120.175735ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:58.448415Z","caller":"traceutil/trace.go:171","msg":"trace[1343506203] transaction","detail":"{read_only:false; response_revision:3188; number_of_response:1; }","duration":"115.298702ms","start":"2026-04-20T14:20:58.333082Z","end":"2026-04-20T14:20:58.448381Z","steps":["trace[1343506203] 'process raft request' (duration: 115.106127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:17.928378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.871188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:21:17.928779Z","caller":"traceutil/trace.go:171","msg":"trace[652164170] transaction","detail":"{read_only:false; response_revision:3288; number_of_response:1; }","duration":"107.924226ms","start":"2026-04-20T14:21:17.820705Z","end":"2026-04-20T14:21:17.928629Z","steps":["trace[652164170] 'compare' (duration: 100.661852ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:26.543312Z","caller":"traceutil/trace.go:171","msg":"trace[2145059247] transaction","detail":"{read_only:false; response_revision:3329; number_of_response:1; }","duration":"125.395967ms","start":"2026-04-20T14:21:26.417888Z","end":"2026-04-20T14:21:26.543284Z","steps":["trace[2145059247] 'process raft request' (duration: 125.212633ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:27.957305Z","caller":"traceutil/trace.go:171","msg":"trace[1930560776] transaction","detail":"{read_only:false; response_revision:3335; number_of_response:1; }","duration":"184.000382ms","start":"2026-04-20T14:21:27.773277Z","end":"2026-04-20T14:21:27.957278Z","steps":["trace[1930560776] 'process raft request' (duration: 183.791137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:28.041829Z","caller":"traceutil/trace.go:171","msg":"trace[1348410854] transaction","detail":"{read_only:false; response_revision:3336; number_of_response:1; }","duration":"251.804878ms","start":"2026-04-20T14:21:27.789975Z","end":"2026-04-20T14:21:28.04178Z","steps":["trace[1348410854] 'process raft request' (duration: 198.425334ms)","trace[1348410854] 'compare' (duration: 53.164029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:28.32222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.942439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:21:28.322567Z","caller":"traceutil/trace.go:171","msg":"trace[1957022226] transaction","detail":"{read_only:false; response_revision:3338; number_of_response:1; }","duration":"221.448421ms","start":"2026-04-20T14:21:28.101095Z","end":"2026-04-20T14:21:28.322543Z","steps":["trace[1957022226] 'process raft request' (duration: 52.063292ms)","trace[1957022226] 'compare' (duration: 168.296594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:28.322827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.439832ms","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-20T14:21:28.32293Z","caller":"traceutil/trace.go:171","msg":"trace[786677783] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3338; }","duration":"123.594646ms","start":"2026-04-20T14:21:28.199309Z","end":"2026-04-20T14:21:28.322904Z","steps":["trace[786677783] 'agreement among raft nodes before linearized reading' (duration: 123.318679ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:28.323034Z","caller":"traceutil/trace.go:171","msg":"trace[1077100289] linearizableReadLoop","detail":"{readStateIndex:3465; appliedIndex:3464; }","duration":"123.116334ms","start":"2026-04-20T14:21:28.199369Z","end":"2026-04-20T14:21:28.322486Z","steps":["trace[1077100289] 'read index received' (duration: 109.472µs)","trace[1077100289] 'applied index is now lower than readState.Index' (duration: 123.004752ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:39.390592Z","caller":"traceutil/trace.go:171","msg":"trace[754736426] transaction","detail":"{read_only:false; response_revision:3394; number_of_response:1; }","duration":"113.742113ms","start":"2026-04-20T14:21:39.276819Z","end":"2026-04-20T14:21:39.390561Z","steps":["trace[754736426] 'process raft request' (duration: 113.400525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:41.1436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.000219ms","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-20T14:21:41.143726Z","caller":"traceutil/trace.go:171","msg":"trace[749157479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3401; }","duration":"153.224984ms","start":"2026-04-20T14:21:40.990469Z","end":"2026-04-20T14:21:41.143694Z","steps":["trace[749157479] 'count revisions from in-memory index tree' (duration: 152.897276ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:41.143777Z","caller":"traceutil/trace.go:171","msg":"trace[1321700195] transaction","detail":"{read_only:false; response_revision:3402; number_of_response:1; }","duration":"101.981302ms","start":"2026-04-20T14:21:41.041759Z","end":"2026-04-20T14:21:41.143741Z","steps":["trace[1321700195] 'process raft request' (duration: 38.653539ms)","trace[1321700195] 'compare' (duration: 63.162029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:47.690441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.535675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:21:47.690576Z","caller":"traceutil/trace.go:171","msg":"trace[2092413420] linearizableReadLoop","detail":"{readStateIndex:3604; appliedIndex:3603; }","duration":"429.548182ms","start":"2026-04-20T14:21:47.261006Z","end":"2026-04-20T14:21:47.690554Z","steps":["trace[2092413420] 'read index received' (duration: 27.771961ms)","trace[2092413420] 'applied index is now lower than readState.Index' (duration: 401.774481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:47.690698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.691165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:47.690672Z","caller":"traceutil/trace.go:171","msg":"trace[1282697083] transaction","detail":"{read_only:false; response_revision:3471; number_of_response:1; }","duration":"517.624373ms","start":"2026-04-20T14:21:47.173007Z","end":"2026-04-20T14:21:47.690631Z","steps":["trace[1282697083] 'process raft request' (duration: 115.786011ms)","trace[1282697083] 'compare' (duration: 401.390822ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:47.690743Z","caller":"traceutil/trace.go:171","msg":"trace[1447030013] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:3471; }","duration":"429.775967ms","start":"2026-04-20T14:21:47.260952Z","end":"2026-04-20T14:21:47.690728Z","steps":["trace[1447030013] 'agreement among raft nodes before linearized reading' (duration: 429.696965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:47.690782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:47.260923Z","time spent":"429.849649ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:21:47.691067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:47.172979Z","time spent":"517.784736ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:47.755417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.944643ms","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-20T14:21:47.755532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.268585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:47.75562Z","caller":"traceutil/trace.go:171","msg":"trace[1281255314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3471; }","duration":"327.152108ms","start":"2026-04-20T14:21:47.428444Z","end":"2026-04-20T14:21:47.755596Z","steps":["trace[1281255314] 'agreement among raft nodes before linearized reading' (duration: 326.80715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:47.755613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.401863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T14:21:47.755514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.811806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T14:21:47.755665Z","caller":"traceutil/trace.go:171","msg":"trace[1554364680] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:3471; }","duration":"315.426298ms","start":"2026-04-20T14:21:47.440213Z","end":"2026-04-20T14:21:47.755639Z","steps":["trace[1554364680] 'agreement among raft nodes before linearized reading' (duration: 315.233014ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:47.755722Z","caller":"traceutil/trace.go:171","msg":"trace[1304805887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3471; }","duration":"303.049622ms","start":"2026-04-20T14:21:47.452652Z","end":"2026-04-20T14:21:47.755702Z","steps":["trace[1304805887] 'agreement among raft nodes before linearized reading' (duration: 302.640653ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:47.755719Z","caller":"traceutil/trace.go:171","msg":"trace[109208121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3471; }","duration":"314.550057ms","start":"2026-04-20T14:21:47.441137Z","end":"2026-04-20T14:21:47.755687Z","steps":["trace[109208121] 'agreement among raft nodes before linearized reading' (duration: 314.212169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:47.755741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:47.440199Z","time spent":"315.52825ms","remote":"127.0.0.1:47450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:21:47.75579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:47.452645Z","time spent":"303.123984ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:21:47.755802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:47.441118Z","time spent":"314.66898ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:21:47.755677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:47.428381Z","time spent":"327.282732ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:22:05.596131Z","caller":"traceutil/trace.go:171","msg":"trace[1836950560] transaction","detail":"{read_only:false; response_revision:3622; number_of_response:1; }","duration":"330.934576ms","start":"2026-04-20T14:22:05.265169Z","end":"2026-04-20T14:22:05.596103Z","steps":["trace[1836950560] 'process raft request' (duration: 330.740561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:05.596533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:05.265138Z","time spent":"331.126231ms","remote":"127.0.0.1:47666","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-20T14:22:06.367201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225630558716478,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:06.868157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225630558716478,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:07.393706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225630558716478,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:07.39464Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.629621479s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T14:22:07.395508Z","caller":"traceutil/trace.go:171","msg":"trace[1896268633] transaction","detail":"{read_only:false; response_revision:3623; number_of_response:1; }","duration":"1.63052963s","start":"2026-04-20T14:22:05.764944Z","end":"2026-04-20T14:22:07.395473Z","steps":["trace[1896268633] 'process raft request' (duration: 1.630338916s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:07.39571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:05.764923Z","time spent":"1.630685923s","remote":"127.0.0.1:47666","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-20T14:22:07.397066Z","caller":"traceutil/trace.go:171","msg":"trace[1375203475] transaction","detail":"{read_only:false; response_revision:3625; number_of_response:1; }","duration":"1.170496724s","start":"2026-04-20T14:22:06.226451Z","end":"2026-04-20T14:22:07.396948Z","steps":["trace[1375203475] 'process raft request' (duration: 1.170413162s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:07.397321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:06.226425Z","time spent":"1.17080214s","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:22:07.39745Z","caller":"traceutil/trace.go:171","msg":"trace[1347714953] transaction","detail":"{read_only:false; response_revision:3626; number_of_response:1; }","duration":"913.302671ms","start":"2026-04-20T14:22:06.484123Z","end":"2026-04-20T14:22:07.397425Z","steps":["trace[1347714953] 'process raft request' (duration: 912.787989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:07.397616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:06.4841Z","time spent":"913.448296ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:22:07.398503Z","caller":"traceutil/trace.go:171","msg":"trace[73014190] transaction","detail":"{read_only:false; response_revision:3624; number_of_response:1; }","duration":"1.361988054s","start":"2026-04-20T14:22:06.036488Z","end":"2026-04-20T14:22:07.398477Z","steps":["trace[73014190] 'process raft request' (duration: 1.360226673s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:07.398635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:06.036458Z","time spent":"1.362085316s","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:22:07.86718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000580982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T14:22:07.867281Z","caller":"traceutil/trace.go:171","msg":"trace[351677084] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000751685s","start":"2026-04-20T14:22:05.866508Z","end":"2026-04-20T14:22:07.86726Z","steps":["trace[351677084] 'agreement among raft nodes before linearized reading' (duration: 2.000623133s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:07.867333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:05.866487Z","time spent":"2.000837758s","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 14:22:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:22:08.267843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.522689ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T14:22:08.268161Z","caller":"traceutil/trace.go:171","msg":"trace[974310177] linearizableReadLoop","detail":"{readStateIndex:3763; appliedIndex:3760; }","duration":"2.40156419s","start":"2026-04-20T14:22:05.86656Z","end":"2026-04-20T14:22:08.268125Z","steps":["trace[974310177] 'read index received' (duration: 1.529885205s)","trace[974310177] 'applied index is now lower than readState.Index' (duration: 871.677525ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:08.269002Z","caller":"traceutil/trace.go:171","msg":"trace[846316705] transaction","detail":"{read_only:false; response_revision:3627; number_of_response:1; }","duration":"666.154203ms","start":"2026-04-20T14:22:07.602821Z","end":"2026-04-20T14:22:08.268975Z","steps":["trace[846316705] 'process raft request' (duration: 665.1541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:08.269153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:07.602773Z","time spent":"666.301206ms","remote":"127.0.0.1:47666","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-20T14:22:08.272369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.821995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:22:08.272435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.392388023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-04-20T14:22:08.272475Z","caller":"traceutil/trace.go:171","msg":"trace[867241397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3627; }","duration":"404.944628ms","start":"2026-04-20T14:22:07.867505Z","end":"2026-04-20T14:22:08.27245Z","steps":["trace[867241397] 'agreement among raft nodes before linearized reading' (duration: 401.5027ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:08.272513Z","caller":"traceutil/trace.go:171","msg":"trace[161391343] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3627; }","duration":"2.392500186s","start":"2026-04-20T14:22:05.879992Z","end":"2026-04-20T14:22:08.272492Z","steps":["trace[161391343] 'agreement among raft nodes before linearized reading' (duration: 2.389075859s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:08.272532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:07.867439Z","time spent":"405.081441ms","remote":"127.0.0.1:47388","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-20T14:22:08.272573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:05.87998Z","time spent":"2.392583267s","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":1251,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"warn","ts":"2026-04-20T14:22:08.272631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.39359335s","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-20T14:22:08.272671Z","caller":"traceutil/trace.go:171","msg":"trace[1843946333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3627; }","duration":"2.393675992s","start":"2026-04-20T14:22:05.878978Z","end":"2026-04-20T14:22:08.272654Z","steps":["trace[1843946333] 'agreement among raft nodes before linearized reading' (duration: 2.389969569s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:08.272693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:05.878954Z","time spent":"2.393733534s","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:22:08.272783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.21776358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-04-20T14:22:08.272842Z","caller":"traceutil/trace.go:171","msg":"trace[2034993658] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3627; }","duration":"2.217897053s","start":"2026-04-20T14:22:06.054928Z","end":"2026-04-20T14:22:08.272825Z","steps":["trace[2034993658] 'agreement among raft nodes before linearized reading' (duration: 2.214048917s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:08.272886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:06.054909Z","time spent":"2.217964704s","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":1251,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"warn","ts":"2026-04-20T14:22:08.272877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.368924243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T14:22:08.272929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469690124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T14:22:08.272933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.370545268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T14:22:08.272963Z","caller":"traceutil/trace.go:171","msg":"trace[2129526035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3627; }","duration":"1.469765496s","start":"2026-04-20T14:22:06.803189Z","end":"2026-04-20T14:22:08.272955Z","steps":["trace[2129526035] 'agreement among raft nodes before linearized reading' (duration: 1.465831378s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:08.272971Z","caller":"traceutil/trace.go:171","msg":"trace[973537763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3627; }","duration":"2.37061476s","start":"2026-04-20T14:22:05.902347Z","end":"2026-04-20T14:22:08.272962Z","steps":["trace[973537763] 'agreement among raft nodes before linearized reading' (duration: 2.366699992s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:08.27297Z","caller":"traceutil/trace.go:171","msg":"trace[1784703921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3627; }","duration":"2.369032225s","start":"2026-04-20T14:22:05.90392Z","end":"2026-04-20T14:22:08.272952Z","steps":["trace[1784703921] 'agreement among raft nodes before linearized reading' (duration: 2.365139678s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:08.272984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:06.803166Z","time spent":"1.469812737s","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:22:08.272996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:05.902331Z","time spent":"2.370658511s","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:22:08.273005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:05.903891Z","time spent":"2.369107067s","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:22:08.272879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.934023587s","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-20T14:22:08.273068Z","caller":"traceutil/trace.go:171","msg":"trace[1307744711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3627; }","duration":"1.934280103s","start":"2026-04-20T14:22:06.33878Z","end":"2026-04-20T14:22:08.27306Z","steps":["trace[1307744711] 'agreement among raft nodes before linearized reading' (duration: 1.930253893s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:08.273098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:06.338761Z","time spent":"1.934329734s","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T14:22:13.532339Z","caller":"traceutil/trace.go:171","msg":"trace[1843224570] transaction","detail":"{read_only:false; response_revision:3658; number_of_response:1; }","duration":"114.485072ms","start":"2026-04-20T14:22:13.417827Z","end":"2026-04-20T14:22:13.532312Z","steps":["trace[1843224570] 'process raft request' (duration: 114.314329ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:13.537124Z","caller":"traceutil/trace.go:171","msg":"trace[1031310336] transaction","detail":"{read_only:false; response_revision:3659; number_of_response:1; }","duration":"119.199688ms","start":"2026-04-20T14:22:13.417876Z","end":"2026-04-20T14:22:13.537076Z","steps":["trace[1031310336] 'process raft request' (duration: 118.936393ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:13.537215Z","caller":"traceutil/trace.go:171","msg":"trace[726280424] transaction","detail":"{read_only:false; response_revision:3660; number_of_response:1; }","duration":"118.581974ms","start":"2026-04-20T14:22:13.41859Z","end":"2026-04-20T14:22:13.537172Z","steps":["trace[726280424] 'process raft request' (duration: 118.37169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:08.238146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.94019ms","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-20T14:23:08.23826Z","caller":"traceutil/trace.go:171","msg":"trace[302626077] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4018; }","duration":"107.135614ms","start":"2026-04-20T14:23:08.131097Z","end":"2026-04-20T14:23:08.238233Z","steps":["trace[302626077] 'count revisions from in-memory index tree' (duration: 106.810398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:08.238434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.021858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-ceph-cluster\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:23:08.238472Z","caller":"traceutil/trace.go:171","msg":"trace[1571731758] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-ceph-cluster; range_end:; response_count:0; response_revision:4018; }","duration":"134.1265ms","start":"2026-04-20T14:23:08.104333Z","end":"2026-04-20T14:23:08.238459Z","steps":["trace[1571731758] 'range keys from in-memory index tree' (duration: 133.912216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:08.758888Z","caller":"traceutil/trace.go:171","msg":"trace[909276439] transaction","detail":"{read_only:false; response_revision:4023; number_of_response:1; }","duration":"104.322844ms","start":"2026-04-20T14:23:08.654506Z","end":"2026-04-20T14:23:08.758828Z","steps":["trace[909276439] 'process raft request' (duration: 57.794822ms)","trace[909276439] 'compare' (duration: 46.219775ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:08.759179Z","caller":"traceutil/trace.go:171","msg":"trace[2056800201] transaction","detail":"{read_only:false; response_revision:4024; number_of_response:1; }","duration":"103.275312ms","start":"2026-04-20T14:23:08.655859Z","end":"2026-04-20T14:23:08.759134Z","steps":["trace[2056800201] 'process raft request' (duration: 102.887344ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:43.44468Z","caller":"traceutil/trace.go:171","msg":"trace[659525871] transaction","detail":"{read_only:false; response_revision:4498; number_of_response:1; }","duration":"113.750081ms","start":"2026-04-20T14:23:43.330869Z","end":"2026-04-20T14:23:43.444619Z","steps":["trace[659525871] 'process raft request' (duration: 62.254518ms)","trace[659525871] 'compare' (duration: 51.182776ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:43.499446Z","caller":"traceutil/trace.go:171","msg":"trace[1940752177] transaction","detail":"{read_only:false; response_revision:4507; number_of_response:1; }","duration":"105.744725ms","start":"2026-04-20T14:23:43.39367Z","end":"2026-04-20T14:23:43.499414Z","steps":["trace[1940752177] 'process raft request' (duration: 105.533311ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:43.499991Z","caller":"traceutil/trace.go:171","msg":"trace[1463025584] transaction","detail":"{read_only:false; response_revision:4506; number_of_response:1; }","duration":"105.899468ms","start":"2026-04-20T14:23:43.393467Z","end":"2026-04-20T14:23:43.499366Z","steps":["trace[1463025584] 'process raft request' (duration: 105.544571ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:44.481282Z","caller":"traceutil/trace.go:171","msg":"trace[769729497] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"103.568358ms","start":"2026-04-20T14:23:44.377679Z","end":"2026-04-20T14:23:44.481247Z","steps":["trace[769729497] 'process raft request' (duration: 99.741459ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:45.37896Z","caller":"traceutil/trace.go:171","msg":"trace[1004169908] transaction","detail":"{read_only:false; response_revision:4575; number_of_response:1; }","duration":"130.728503ms","start":"2026-04-20T14:23:45.248195Z","end":"2026-04-20T14:23:45.378924Z","steps":["trace[1004169908] 'process raft request' (duration: 122.176055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:49.826226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.302741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T14:23:49.826384Z","caller":"traceutil/trace.go:171","msg":"trace[1975573417] linearizableReadLoop","detail":"{readStateIndex:4857; appliedIndex:4856; }","duration":"222.837601ms","start":"2026-04-20T14:23:49.603521Z","end":"2026-04-20T14:23:49.826359Z","steps":["trace[1975573417] 'read index received' (duration: 62.06419ms)","trace[1975573417] 'applied index is now lower than readState.Index' (duration: 160.771551ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:49.826516Z","caller":"traceutil/trace.go:171","msg":"trace[1234564554] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4678; }","duration":"223.991665ms","start":"2026-04-20T14:23:49.602508Z","end":"2026-04-20T14:23:49.8265Z","steps":["trace[1234564554] 'process raft request' (duration: 63.206734ms)","trace[1234564554] 'compare' (duration: 160.012215ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:23:49.826651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.229009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:23:49.826762Z","caller":"traceutil/trace.go:171","msg":"trace[2073009847] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4678; }","duration":"172.340112ms","start":"2026-04-20T14:23:49.654381Z","end":"2026-04-20T14:23:49.826722Z","steps":["trace[2073009847] 'agreement among raft nodes before linearized reading' (duration: 172.177028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:49.827141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.614517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-20T14:23:49.827174Z","caller":"traceutil/trace.go:171","msg":"trace[106290121] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4678; }","duration":"223.659958ms","start":"2026-04-20T14:23:49.603503Z","end":"2026-04-20T14:23:49.827163Z","steps":["trace[106290121] 'agreement among raft nodes before linearized reading' (duration: 223.517055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:49.827327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.246699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-20T14:23:49.827385Z","caller":"traceutil/trace.go:171","msg":"trace[1064204616] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4678; }","duration":"146.33345ms","start":"2026-04-20T14:23:49.681035Z","end":"2026-04-20T14:23:49.827368Z","steps":["trace[1064204616] 'agreement among raft nodes before linearized reading' (duration: 146.089295ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:50.3576Z","caller":"traceutil/trace.go:171","msg":"trace[1595569330] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"171.262397ms","start":"2026-04-20T14:23:50.186299Z","end":"2026-04-20T14:23:50.357562Z","steps":["trace[1595569330] 'process raft request' (duration: 128.147792ms)","trace[1595569330] 'compare' (duration: 42.944161ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:50.361655Z","caller":"traceutil/trace.go:171","msg":"trace[1450597957] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"169.318906ms","start":"2026-04-20T14:23:50.192285Z","end":"2026-04-20T14:23:50.361604Z","steps":["trace[1450597957] 'process raft request' (duration: 169.087832ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:50.36179Z","caller":"traceutil/trace.go:171","msg":"trace[1567397651] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"129.035051ms","start":"2026-04-20T14:23:50.232738Z","end":"2026-04-20T14:23:50.361773Z","steps":["trace[1567397651] 'process raft request' (duration: 128.802335ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:50.361759Z","caller":"traceutil/trace.go:171","msg":"trace[1144416543] linearizableReadLoop","detail":"{readStateIndex:4872; appliedIndex:4870; }","duration":"146.464113ms","start":"2026-04-20T14:23:50.215264Z","end":"2026-04-20T14:23:50.361728Z","steps":["trace[1144416543] 'read index received' (duration: 99.186911ms)","trace[1144416543] 'applied index is now lower than readState.Index' (duration: 47.275702ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:23:50.362267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.992333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:489647"} {"level":"info","ts":"2026-04-20T14:23:50.362295Z","caller":"traceutil/trace.go:171","msg":"trace[1453172324] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4694; }","duration":"147.081165ms","start":"2026-04-20T14:23:50.215207Z","end":"2026-04-20T14:23:50.362288Z","steps":["trace[1453172324] 'agreement among raft nodes before linearized reading' (duration: 146.690547ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:52.445418Z","caller":"traceutil/trace.go:171","msg":"trace[301093482] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"118.701953ms","start":"2026-04-20T14:23:52.326652Z","end":"2026-04-20T14:23:52.445354Z","steps":["trace[301093482] 'process raft request' (duration: 66.594252ms)","trace[301093482] 'compare' (duration: 51.749773ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:52.445579Z","caller":"traceutil/trace.go:171","msg":"trace[1611854798] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4721; }","duration":"118.303444ms","start":"2026-04-20T14:23:52.327247Z","end":"2026-04-20T14:23:52.44555Z","steps":["trace[1611854798] 'process raft request' (duration: 118.007058ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:01.133394Z","caller":"traceutil/trace.go:171","msg":"trace[1860579582] linearizableReadLoop","detail":"{readStateIndex:5089; appliedIndex:5088; }","duration":"466.837485ms","start":"2026-04-20T14:24:00.666538Z","end":"2026-04-20T14:24:01.133375Z","steps":["trace[1860579582] 'read index received' (duration: 416.956884ms)","trace[1860579582] 'applied index is now lower than readState.Index' (duration: 49.879281ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:01.13341Z","caller":"traceutil/trace.go:171","msg":"trace[789423805] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"651.356188ms","start":"2026-04-20T14:24:00.482018Z","end":"2026-04-20T14:24:01.133374Z","steps":["trace[789423805] 'process raft request' (duration: 601.475807ms)","trace[789423805] 'compare' (duration: 49.644616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:01.133598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:00.481984Z","time spent":"651.501891ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:24:01.13365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.110071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49a228a1-826a-4634-9c59-a6eed66e6838\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T14:24:01.133635Z","caller":"traceutil/trace.go:171","msg":"trace[804913039] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"434.501477ms","start":"2026-04-20T14:24:00.69911Z","end":"2026-04-20T14:24:01.133611Z","steps":["trace[804913039] 'process raft request' (duration: 434.17983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:01.13378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:00.699084Z","time spent":"434.621289ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:24:01.133805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.747168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49a228a1-826a-4634-9c59-a6eed66e6838\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T14:24:01.133827Z","caller":"traceutil/trace.go:171","msg":"trace[831018736] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49a228a1-826a-4634-9c59-a6eed66e6838; range_end:; response_count:1; response_revision:4906; }","duration":"459.807589ms","start":"2026-04-20T14:24:00.674015Z","end":"2026-04-20T14:24:01.133822Z","steps":["trace[831018736] 'agreement among raft nodes before linearized reading' (duration: 459.738598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:01.133849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:00.673996Z","time spent":"459.84821ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49a228a1-826a-4634-9c59-a6eed66e6838\" "} {"level":"info","ts":"2026-04-20T14:24:01.13368Z","caller":"traceutil/trace.go:171","msg":"trace[1982214165] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49a228a1-826a-4634-9c59-a6eed66e6838; range_end:; response_count:1; response_revision:4905; }","duration":"467.234443ms","start":"2026-04-20T14:24:00.666437Z","end":"2026-04-20T14:24:01.133672Z","steps":["trace[1982214165] 'agreement among raft nodes before linearized reading' (duration: 467.07549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:01.133929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:00.6664Z","time spent":"467.515389ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49a228a1-826a-4634-9c59-a6eed66e6838\" "} {"level":"warn","ts":"2026-04-20T14:24:01.134069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.80536ms","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-20T14:24:01.134098Z","caller":"traceutil/trace.go:171","msg":"trace[1990909272] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4906; }","duration":"279.870811ms","start":"2026-04-20T14:24:00.854219Z","end":"2026-04-20T14:24:01.13409Z","steps":["trace[1990909272] 'agreement among raft nodes before linearized reading' (duration: 279.78696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:04.105854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.181149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:04.105971Z","caller":"traceutil/trace.go:171","msg":"trace[243471710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4952; }","duration":"231.346462ms","start":"2026-04-20T14:24:03.8746Z","end":"2026-04-20T14:24:04.105946Z","steps":["trace[243471710] 'range keys from in-memory index tree' (duration: 231.041306ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:09.371003Z","caller":"traceutil/trace.go:171","msg":"trace[19813960] linearizableReadLoop","detail":"{readStateIndex:5227; appliedIndex:5226; }","duration":"220.741762ms","start":"2026-04-20T14:24:09.15024Z","end":"2026-04-20T14:24:09.370982Z","steps":["trace[19813960] 'read index received' (duration: 220.489577ms)","trace[19813960] 'applied index is now lower than readState.Index' (duration: 251.315µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:09.371508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.15691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:24:09.37163Z","caller":"traceutil/trace.go:171","msg":"trace[1277428387] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5036; }","duration":"221.389885ms","start":"2026-04-20T14:24:09.150199Z","end":"2026-04-20T14:24:09.371589Z","steps":["trace[1277428387] 'agreement among raft nodes before linearized reading' (duration: 221.11666ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:09.371551Z","caller":"traceutil/trace.go:171","msg":"trace[1459494887] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"229.302778ms","start":"2026-04-20T14:24:09.142015Z","end":"2026-04-20T14:24:09.371318Z","steps":["trace[1459494887] 'process raft request' (duration: 228.783237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:09.372839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.132387ms","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-20T14:24:09.372903Z","caller":"traceutil/trace.go:171","msg":"trace[1155215799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5038; }","duration":"111.304141ms","start":"2026-04-20T14:24:09.261585Z","end":"2026-04-20T14:24:09.372889Z","steps":["trace[1155215799] 'agreement among raft nodes before linearized reading' (duration: 111.109247ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:09.373629Z","caller":"traceutil/trace.go:171","msg":"trace[317561255] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"201.864373ms","start":"2026-04-20T14:24:09.171752Z","end":"2026-04-20T14:24:09.373617Z","steps":["trace[317561255] 'process raft request' (duration: 200.666409ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:09.373732Z","caller":"traceutil/trace.go:171","msg":"trace[1519469519] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"201.899183ms","start":"2026-04-20T14:24:09.171825Z","end":"2026-04-20T14:24:09.373724Z","steps":["trace[1519469519] 'process raft request' (duration: 200.72651ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:14.448525Z","caller":"traceutil/trace.go:171","msg":"trace[971435930] linearizableReadLoop","detail":"{readStateIndex:5275; appliedIndex:5274; }","duration":"135.113363ms","start":"2026-04-20T14:24:14.313388Z","end":"2026-04-20T14:24:14.448501Z","steps":["trace[971435930] 'read index received' (duration: 134.828137ms)","trace[971435930] 'applied index is now lower than readState.Index' (duration: 283.946µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:14.449259Z","caller":"traceutil/trace.go:171","msg":"trace[955518372] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"169.452549ms","start":"2026-04-20T14:24:14.279145Z","end":"2026-04-20T14:24:14.448597Z","steps":["trace[955518372] 'process raft request' (duration: 169.180004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:14.449547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.004521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:14.449734Z","caller":"traceutil/trace.go:171","msg":"trace[1734620571] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:5083; }","duration":"136.281917ms","start":"2026-04-20T14:24:14.313351Z","end":"2026-04-20T14:24:14.449633Z","steps":["trace[1734620571] 'agreement among raft nodes before linearized reading' (duration: 135.275927ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:14.823231Z","caller":"traceutil/trace.go:171","msg":"trace[1811983047] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5277; }","duration":"163.794642ms","start":"2026-04-20T14:24:14.659417Z","end":"2026-04-20T14:24:14.823212Z","steps":["trace[1811983047] 'read index received' (duration: 163.589897ms)","trace[1811983047] 'applied index is now lower than readState.Index' (duration: 204.005µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:14.823421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.814093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"warn","ts":"2026-04-20T14:24:14.823422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.986926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a816b9a9503bd2\" ","response":"range_response_count:1 size:746"} {"level":"info","ts":"2026-04-20T14:24:14.823459Z","caller":"traceutil/trace.go:171","msg":"trace[732416834] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5086; }","duration":"163.875544ms","start":"2026-04-20T14:24:14.659574Z","end":"2026-04-20T14:24:14.82345Z","steps":["trace[732416834] 'agreement among raft nodes before linearized reading' (duration: 163.747012ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:14.823469Z","caller":"traceutil/trace.go:171","msg":"trace[491654772] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a816b9a9503bd2; range_end:; response_count:1; response_revision:5086; }","duration":"164.056387ms","start":"2026-04-20T14:24:14.659399Z","end":"2026-04-20T14:24:14.823455Z","steps":["trace[491654772] 'agreement among raft nodes before linearized reading' (duration: 163.904204ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:14.823266Z","caller":"traceutil/trace.go:171","msg":"trace[1216363439] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"167.045769ms","start":"2026-04-20T14:24:14.656207Z","end":"2026-04-20T14:24:14.823252Z","steps":["trace[1216363439] 'process raft request' (duration: 166.871495ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:14.950952Z","caller":"traceutil/trace.go:171","msg":"trace[1908323739] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"114.836137ms","start":"2026-04-20T14:24:14.836089Z","end":"2026-04-20T14:24:14.950925Z","steps":["trace[1908323739] 'process raft request' (duration: 114.391298ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:14.950952Z","caller":"traceutil/trace.go:171","msg":"trace[1108746487] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"112.161062ms","start":"2026-04-20T14:24:14.83874Z","end":"2026-04-20T14:24:14.950901Z","steps":["trace[1108746487] 'process raft request' (duration: 111.972238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:14.951019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.186343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a816b9a99713d1\" ","response":"range_response_count:1 size:740"} {"level":"warn","ts":"2026-04-20T14:24:14.95095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.786426ms","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-20T14:24:14.951155Z","caller":"traceutil/trace.go:171","msg":"trace[471708911] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5091; }","duration":"116.023491ms","start":"2026-04-20T14:24:14.83512Z","end":"2026-04-20T14:24:14.951143Z","steps":["trace[471708911] 'agreement among raft nodes before linearized reading' (duration: 115.704954ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:14.951069Z","caller":"traceutil/trace.go:171","msg":"trace[287963205] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a816b9a99713d1; range_end:; response_count:1; response_revision:5091; }","duration":"114.258735ms","start":"2026-04-20T14:24:14.836797Z","end":"2026-04-20T14:24:14.951056Z","steps":["trace[287963205] 'agreement among raft nodes before linearized reading' (duration: 114.02416ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:50.10345Z","caller":"traceutil/trace.go:171","msg":"trace[555726518] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"192.822611ms","start":"2026-04-20T14:24:49.910601Z","end":"2026-04-20T14:24:50.103424Z","steps":["trace[555726518] 'process raft request' (duration: 114.133339ms)","trace[555726518] 'compare' (duration: 78.536379ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:50.305058Z","caller":"traceutil/trace.go:171","msg":"trace[1819737554] linearizableReadLoop","detail":"{readStateIndex:5507; appliedIndex:5506; }","duration":"119.983557ms","start":"2026-04-20T14:24:50.185032Z","end":"2026-04-20T14:24:50.305016Z","steps":["trace[1819737554] 'read index received' (duration: 97.027323ms)","trace[1819737554] 'applied index is now lower than readState.Index' (duration: 22.954254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:50.305417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.347895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-20T14:24:50.30538Z","caller":"traceutil/trace.go:171","msg":"trace[1470599118] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"123.181153ms","start":"2026-04-20T14:24:50.181927Z","end":"2026-04-20T14:24:50.305108Z","steps":["trace[1470599118] 'process raft request' (duration: 100.267589ms)","trace[1470599118] 'compare' (duration: 22.500526ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:50.305467Z","caller":"traceutil/trace.go:171","msg":"trace[699478100] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5307; }","duration":"120.460417ms","start":"2026-04-20T14:24:50.184988Z","end":"2026-04-20T14:24:50.305448Z","steps":["trace[699478100] 'agreement among raft nodes before linearized reading' (duration: 120.200021ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:52.72074Z","caller":"traceutil/trace.go:171","msg":"trace[1815838592] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"125.679132ms","start":"2026-04-20T14:24:52.595028Z","end":"2026-04-20T14:24:52.720708Z","steps":["trace[1815838592] 'process raft request' (duration: 125.445787ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:58.859171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2879} {"level":"info","ts":"2026-04-20T14:24:58.950937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2879,"took":"90.081668ms","hash":2553202992,"current-db-size-bytes":53522432,"current-db-size":"54 MB","current-db-size-in-use-bytes":28262400,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-20T14:24:58.951136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2553202992,"revision":2879,"compact-revision":-1} {"level":"info","ts":"2026-04-20T14:25:03.42836Z","caller":"traceutil/trace.go:171","msg":"trace[1504978438] linearizableReadLoop","detail":"{readStateIndex:5677; appliedIndex:5676; }","duration":"169.647229ms","start":"2026-04-20T14:25:03.258688Z","end":"2026-04-20T14:25:03.428335Z","steps":["trace[1504978438] 'read index received' (duration: 169.465085ms)","trace[1504978438] 'applied index is now lower than readState.Index' (duration: 180.954µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:03.428461Z","caller":"traceutil/trace.go:171","msg":"trace[1646824649] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"218.596086ms","start":"2026-04-20T14:25:03.209832Z","end":"2026-04-20T14:25:03.428428Z","steps":["trace[1646824649] 'process raft request' (duration: 218.33496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:03.428607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.887353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:25:03.428704Z","caller":"traceutil/trace.go:171","msg":"trace[46578720] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5472; }","duration":"170.041477ms","start":"2026-04-20T14:25:03.258643Z","end":"2026-04-20T14:25:03.428685Z","steps":["trace[46578720] 'agreement among raft nodes before linearized reading' (duration: 169.829512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:03.428558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.813023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:25:03.428855Z","caller":"traceutil/trace.go:171","msg":"trace[1161046063] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5472; }","duration":"170.148959ms","start":"2026-04-20T14:25:03.258671Z","end":"2026-04-20T14:25:03.42882Z","steps":["trace[1161046063] 'agreement among raft nodes before linearized reading' (duration: 169.811153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:03.428872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.126848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-04-20T14:25:03.429051Z","caller":"traceutil/trace.go:171","msg":"trace[893996624] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5472; }","duration":"169.351213ms","start":"2026-04-20T14:25:03.259682Z","end":"2026-04-20T14:25:03.429034Z","steps":["trace[893996624] 'agreement among raft nodes before linearized reading' (duration: 168.896774ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:03.967975Z","caller":"traceutil/trace.go:171","msg":"trace[756436436] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"197.853637ms","start":"2026-04-20T14:25:03.770096Z","end":"2026-04-20T14:25:03.96795Z","steps":["trace[756436436] 'process raft request' (duration: 193.470728ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:03.968248Z","caller":"traceutil/trace.go:171","msg":"trace[879973541] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"198.01542ms","start":"2026-04-20T14:25:03.770214Z","end":"2026-04-20T14:25:03.968229Z","steps":["trace[879973541] 'process raft request' (duration: 197.670653ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:03.96845Z","caller":"traceutil/trace.go:171","msg":"trace[1207316220] linearizableReadLoop","detail":"{readStateIndex:5684; appliedIndex:5681; }","duration":"100.392873ms","start":"2026-04-20T14:25:03.868045Z","end":"2026-04-20T14:25:03.968438Z","steps":["trace[1207316220] 'read index received' (duration: 95.587356ms)","trace[1207316220] 'applied index is now lower than readState.Index' (duration: 4.804247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:03.968582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.519036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:25:03.96865Z","caller":"traceutil/trace.go:171","msg":"trace[1376421414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5479; }","duration":"100.645078ms","start":"2026-04-20T14:25:03.867993Z","end":"2026-04-20T14:25:03.968638Z","steps":["trace[1376421414] 'agreement among raft nodes before linearized reading' (duration: 100.516986ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:03.968657Z","caller":"traceutil/trace.go:171","msg":"trace[1875798435] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"198.364767ms","start":"2026-04-20T14:25:03.770266Z","end":"2026-04-20T14:25:03.968631Z","steps":["trace[1875798435] 'process raft request' (duration: 197.910228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:07.930063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225630558720883,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:25:08.035579Z","caller":"traceutil/trace.go:171","msg":"trace[673057296] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"648.89013ms","start":"2026-04-20T14:25:07.38667Z","end":"2026-04-20T14:25:08.03556Z","steps":["trace[673057296] 'process raft request' (duration: 648.751787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:08.035714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:07.386651Z","time spent":"648.987771ms","remote":"127.0.0.1:47562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12032,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:25:08.048552Z","caller":"traceutil/trace.go:171","msg":"trace[621829339] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"354.947703ms","start":"2026-04-20T14:25:07.693552Z","end":"2026-04-20T14:25:08.0485Z","steps":["trace[621829339] 'process raft request' (duration: 354.713348ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:08.04858Z","caller":"traceutil/trace.go:171","msg":"trace[1442396560] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"336.514363ms","start":"2026-04-20T14:25:07.712043Z","end":"2026-04-20T14:25:08.048557Z","steps":["trace[1442396560] 'process raft request' (duration: 336.36002ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:08.048856Z","caller":"traceutil/trace.go:171","msg":"trace[1588385847] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"279.214568ms","start":"2026-04-20T14:25:07.769631Z","end":"2026-04-20T14:25:08.048845Z","steps":["trace[1588385847] 'process raft request' (duration: 278.844711ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:08.048793Z","caller":"traceutil/trace.go:171","msg":"trace[2041942055] linearizableReadLoop","detail":"{readStateIndex:5705; appliedIndex:5701; }","duration":"619.035829ms","start":"2026-04-20T14:25:07.429732Z","end":"2026-04-20T14:25:08.048767Z","steps":["trace[2041942055] 'read index received' (duration: 606.056028ms)","trace[2041942055] 'applied index is now lower than readState.Index' (duration: 12.978411ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:08.049068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.334074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-20T14:25:08.048779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:07.693527Z","time spent":"355.097225ms","remote":"127.0.0.1:47666","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-20T14:25:08.049123Z","caller":"traceutil/trace.go:171","msg":"trace[1574791686] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5499; }","duration":"619.419097ms","start":"2026-04-20T14:25:07.429689Z","end":"2026-04-20T14:25:08.049108Z","steps":["trace[1574791686] 'agreement among raft nodes before linearized reading' (duration: 619.149931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:08.049176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:07.429671Z","time spent":"619.494008ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T14:25:08.049238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.710482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:25:08.049291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.597254ms","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-20T14:25:08.049337Z","caller":"traceutil/trace.go:171","msg":"trace[495792869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5499; }","duration":"254.719867ms","start":"2026-04-20T14:25:07.794589Z","end":"2026-04-20T14:25:08.049309Z","steps":["trace[495792869] 'agreement among raft nodes before linearized reading' (duration: 254.574723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:08.04929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:07.712032Z","time spent":"336.830989ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:25:08.049449Z","caller":"traceutil/trace.go:171","msg":"trace[1063787190] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5499; }","duration":"613.854935ms","start":"2026-04-20T14:25:07.435447Z","end":"2026-04-20T14:25:08.049302Z","steps":["trace[1063787190] 'agreement among raft nodes before linearized reading' (duration: 613.711602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:08.04957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.14621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:25:08.049639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:07.435424Z","time spent":"614.173821ms","remote":"127.0.0.1:47628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-04-20T14:25:08.049768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.983956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:25:08.049804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.122697ms","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-20T14:25:08.049837Z","caller":"traceutil/trace.go:171","msg":"trace[680710147] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5499; }","duration":"611.078878ms","start":"2026-04-20T14:25:07.438741Z","end":"2026-04-20T14:25:08.04982Z","steps":["trace[680710147] 'agreement among raft nodes before linearized reading' (duration: 610.975206ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:08.049845Z","caller":"traceutil/trace.go:171","msg":"trace[211983615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5499; }","duration":"270.202919ms","start":"2026-04-20T14:25:07.779629Z","end":"2026-04-20T14:25:08.049832Z","steps":["trace[211983615] 'agreement among raft nodes before linearized reading' (duration: 270.044256ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:08.049643Z","caller":"traceutil/trace.go:171","msg":"trace[273359852] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5499; }","duration":"384.248514ms","start":"2026-04-20T14:25:07.665368Z","end":"2026-04-20T14:25:08.049617Z","steps":["trace[273359852] 'agreement among raft nodes before linearized reading' (duration: 384.091971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:08.049889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:07.438729Z","time spent":"611.14953ms","remote":"127.0.0.1:47628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-20T14:25:08.049878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.629299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"warn","ts":"2026-04-20T14:25:08.049907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:07.665347Z","time spent":"384.54339ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:25:08.049953Z","caller":"traceutil/trace.go:171","msg":"trace[425799321] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5499; }","duration":"610.823273ms","start":"2026-04-20T14:25:07.43911Z","end":"2026-04-20T14:25:08.049934Z","steps":["trace[425799321] 'agreement among raft nodes before linearized reading' (duration: 610.445565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:08.049967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.034833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:25:08.050008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:07.439102Z","time spent":"610.892794ms","remote":"127.0.0.1:47628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6179,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"info","ts":"2026-04-20T14:25:08.050037Z","caller":"traceutil/trace.go:171","msg":"trace[304658919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5499; }","duration":"181.133955ms","start":"2026-04-20T14:25:07.868861Z","end":"2026-04-20T14:25:08.049995Z","steps":["trace[304658919] 'agreement among raft nodes before linearized reading' (duration: 181.032673ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:25.96002Z","caller":"traceutil/trace.go:171","msg":"trace[1143701478] transaction","detail":"{read_only:false; response_revision:5639; number_of_response:1; }","duration":"112.126545ms","start":"2026-04-20T14:25:25.847858Z","end":"2026-04-20T14:25:25.959985Z","steps":["trace[1143701478] 'process raft request' (duration: 111.86476ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:26.307799Z","caller":"traceutil/trace.go:171","msg":"trace[2069970225] transaction","detail":"{read_only:false; response_revision:5641; number_of_response:1; }","duration":"152.90845ms","start":"2026-04-20T14:25:26.154838Z","end":"2026-04-20T14:25:26.307746Z","steps":["trace[2069970225] 'process raft request' (duration: 53.324628ms)","trace[2069970225] 'compare' (duration: 99.265556ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:26.307854Z","caller":"traceutil/trace.go:171","msg":"trace[1530173681] linearizableReadLoop","detail":"{readStateIndex:5850; appliedIndex:5849; }","duration":"131.865579ms","start":"2026-04-20T14:25:26.175959Z","end":"2026-04-20T14:25:26.307825Z","steps":["trace[1530173681] 'read index received' (duration: 32.216745ms)","trace[1530173681] 'applied index is now lower than readState.Index' (duration: 99.645394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:26.308082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.110705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:1 size:5995"} {"level":"info","ts":"2026-04-20T14:25:26.308143Z","caller":"traceutil/trace.go:171","msg":"trace[738475173] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:1; response_revision:5642; }","duration":"132.219836ms","start":"2026-04-20T14:25:26.175906Z","end":"2026-04-20T14:25:26.308126Z","steps":["trace[738475173] 'agreement among raft nodes before linearized reading' (duration: 132.027842ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:26.30891Z","caller":"traceutil/trace.go:171","msg":"trace[371316773] transaction","detail":"{read_only:false; response_revision:5642; number_of_response:1; }","duration":"106.155115ms","start":"2026-04-20T14:25:26.201622Z","end":"2026-04-20T14:25:26.307778Z","steps":["trace[371316773] 'process raft request' (duration: 106.067063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:32.643589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.354014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:25:32.64403Z","caller":"traceutil/trace.go:171","msg":"trace[737134118] transaction","detail":"{read_only:false; response_revision:5695; number_of_response:1; }","duration":"198.58758ms","start":"2026-04-20T14:25:32.445408Z","end":"2026-04-20T14:25:32.643996Z","steps":["trace[737134118] 'process raft request' (duration: 17.752806ms)","trace[737134118] 'compare' (duration: 180.258792ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:32.644296Z","caller":"traceutil/trace.go:171","msg":"trace[1280600312] linearizableReadLoop","detail":"{readStateIndex:5906; appliedIndex:5905; }","duration":"168.484557ms","start":"2026-04-20T14:25:32.475793Z","end":"2026-04-20T14:25:32.644277Z","steps":["trace[1280600312] 'read index received' (duration: 42.091µs)","trace[1280600312] 'applied index is now lower than readState.Index' (duration: 168.440136ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:32.644565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.839065ms","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-20T14:25:32.644614Z","caller":"traceutil/trace.go:171","msg":"trace[506212985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5695; }","duration":"168.925936ms","start":"2026-04-20T14:25:32.475677Z","end":"2026-04-20T14:25:32.644603Z","steps":["trace[506212985] 'agreement among raft nodes before linearized reading' (duration: 168.762412ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:32.802151Z","caller":"traceutil/trace.go:171","msg":"trace[1359881126] transaction","detail":"{read_only:false; response_revision:5697; number_of_response:1; }","duration":"152.174792ms","start":"2026-04-20T14:25:32.649952Z","end":"2026-04-20T14:25:32.802127Z","steps":["trace[1359881126] 'process raft request' (duration: 152.10824ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:32.802425Z","caller":"traceutil/trace.go:171","msg":"trace[1561340808] linearizableReadLoop","detail":"{readStateIndex:5907; appliedIndex:5906; }","duration":"158.025549ms","start":"2026-04-20T14:25:32.644359Z","end":"2026-04-20T14:25:32.802385Z","steps":["trace[1561340808] 'read index received' (duration: 107.650682ms)","trace[1561340808] 'applied index is now lower than readState.Index' (duration: 50.368177ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:32.802601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.331913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T14:25:32.8026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.804893ms","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-20T14:25:32.802673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.975686ms","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-20T14:25:32.802676Z","caller":"traceutil/trace.go:171","msg":"trace[1274424007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5697; }","duration":"268.554508ms","start":"2026-04-20T14:25:32.534102Z","end":"2026-04-20T14:25:32.802657Z","steps":["trace[1274424007] 'agreement among raft nodes before linearized reading' (duration: 268.339224ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:32.802681Z","caller":"traceutil/trace.go:171","msg":"trace[2037876959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5697; }","duration":"267.939695ms","start":"2026-04-20T14:25:32.534724Z","end":"2026-04-20T14:25:32.802663Z","steps":["trace[2037876959] 'agreement among raft nodes before linearized reading' (duration: 267.69341ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:32.802713Z","caller":"traceutil/trace.go:171","msg":"trace[492769515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5697; }","duration":"301.074778ms","start":"2026-04-20T14:25:32.501626Z","end":"2026-04-20T14:25:32.8027Z","steps":["trace[492769515] 'agreement among raft nodes before linearized reading' (duration: 300.875094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:32.802755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:32.501604Z","time spent":"301.143199ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T14:25:32.802846Z","caller":"traceutil/trace.go:171","msg":"trace[1953506838] transaction","detail":"{read_only:false; response_revision:5696; number_of_response:1; }","duration":"313.060767ms","start":"2026-04-20T14:25:32.489764Z","end":"2026-04-20T14:25:32.802825Z","steps":["trace[1953506838] 'process raft request' (duration: 262.227912ms)","trace[1953506838] 'compare' (duration: 49.821675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:32.802959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:32.489711Z","time spent":"313.17407ms","remote":"127.0.0.1:47666","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-20T14:26:56.653428Z","caller":"traceutil/trace.go:171","msg":"trace[1303005049] transaction","detail":"{read_only:false; response_revision:6176; number_of_response:1; }","duration":"157.49607ms","start":"2026-04-20T14:26:56.495912Z","end":"2026-04-20T14:26:56.653408Z","steps":["trace[1303005049] 'process raft request' (duration: 157.358577ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:56.790291Z","caller":"traceutil/trace.go:171","msg":"trace[2007315963] transaction","detail":"{read_only:false; response_revision:6177; number_of_response:1; }","duration":"130.05783ms","start":"2026-04-20T14:26:56.660201Z","end":"2026-04-20T14:26:56.790259Z","steps":["trace[2007315963] 'process raft request' (duration: 123.668794ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:56.805848Z","caller":"traceutil/trace.go:171","msg":"trace[445756637] transaction","detail":"{read_only:false; response_revision:6178; number_of_response:1; }","duration":"143.497954ms","start":"2026-04-20T14:26:56.662325Z","end":"2026-04-20T14:26:56.805823Z","steps":["trace[445756637] 'process raft request' (duration: 143.29482ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:56.806236Z","caller":"traceutil/trace.go:171","msg":"trace[707743701] transaction","detail":"{read_only:false; response_revision:6179; number_of_response:1; }","duration":"142.842601ms","start":"2026-04-20T14:26:56.663383Z","end":"2026-04-20T14:26:56.806226Z","steps":["trace[707743701] 'process raft request' (duration: 142.380592ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:56.80649Z","caller":"traceutil/trace.go:171","msg":"trace[869105233] transaction","detail":"{read_only:false; response_revision:6180; number_of_response:1; }","duration":"140.532935ms","start":"2026-04-20T14:26:56.665924Z","end":"2026-04-20T14:26:56.806457Z","steps":["trace[869105233] 'process raft request' (duration: 140.162278ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:05.696212Z","caller":"traceutil/trace.go:171","msg":"trace[653536315] linearizableReadLoop","detail":"{readStateIndex:6974; appliedIndex:6973; }","duration":"222.979988ms","start":"2026-04-20T14:28:05.473208Z","end":"2026-04-20T14:28:05.696188Z","steps":["trace[653536315] 'read index received' (duration: 222.814855ms)","trace[653536315] 'applied index is now lower than readState.Index' (duration: 164.293µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:05.696324Z","caller":"traceutil/trace.go:171","msg":"trace[1202384676] transaction","detail":"{read_only:false; response_revision:6728; number_of_response:1; }","duration":"224.369275ms","start":"2026-04-20T14:28:05.471933Z","end":"2026-04-20T14:28:05.696302Z","steps":["trace[1202384676] 'process raft request' (duration: 224.11139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:05.696602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.684039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-04-20T14:28:05.696645Z","caller":"traceutil/trace.go:171","msg":"trace[2005713267] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6728; }","duration":"205.758111ms","start":"2026-04-20T14:28:05.490874Z","end":"2026-04-20T14:28:05.696632Z","steps":["trace[2005713267] 'agreement among raft nodes before linearized reading' (duration: 205.663589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:05.696501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.268394ms","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-20T14:28:05.696943Z","caller":"traceutil/trace.go:171","msg":"trace[2140566873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6728; }","duration":"223.737603ms","start":"2026-04-20T14:28:05.47319Z","end":"2026-04-20T14:28:05.696928Z","steps":["trace[2140566873] 'agreement among raft nodes before linearized reading' (duration: 223.170002ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:24.029084Z","caller":"traceutil/trace.go:171","msg":"trace[635400533] transaction","detail":"{read_only:false; response_revision:6879; number_of_response:1; }","duration":"126.721993ms","start":"2026-04-20T14:28:23.902343Z","end":"2026-04-20T14:28:24.029065Z","steps":["trace[635400533] 'process raft request' (duration: 60.058822ms)","trace[635400533] 'compare' (duration: 66.254383ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:24.02921Z","caller":"traceutil/trace.go:171","msg":"trace[403745584] linearizableReadLoop","detail":"{readStateIndex:7131; appliedIndex:7130; }","duration":"123.621093ms","start":"2026-04-20T14:28:23.905578Z","end":"2026-04-20T14:28:24.029199Z","steps":["trace[403745584] 'read index received' (duration: 56.907371ms)","trace[403745584] 'applied index is now lower than readState.Index' (duration: 66.712942ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:24.029421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.828967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T14:28:24.029452Z","caller":"traceutil/trace.go:171","msg":"trace[608699518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6880; }","duration":"123.891967ms","start":"2026-04-20T14:28:23.905553Z","end":"2026-04-20T14:28:24.029445Z","steps":["trace[608699518] 'agreement among raft nodes before linearized reading' (duration: 123.770075ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:30.11994Z","caller":"traceutil/trace.go:171","msg":"trace[807401688] transaction","detail":"{read_only:false; response_revision:6905; number_of_response:1; }","duration":"240.577023ms","start":"2026-04-20T14:28:29.879342Z","end":"2026-04-20T14:28:30.119919Z","steps":["trace[807401688] 'process raft request' (duration: 240.288048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:30.120208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.023575ms","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-20T14:28:30.120566Z","caller":"traceutil/trace.go:171","msg":"trace[2063612094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6905; }","duration":"195.445623ms","start":"2026-04-20T14:28:29.925103Z","end":"2026-04-20T14:28:30.120549Z","steps":["trace[2063612094] 'agreement among raft nodes before linearized reading' (duration: 194.974924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:30.121248Z","caller":"traceutil/trace.go:171","msg":"trace[1884793571] linearizableReadLoop","detail":"{readStateIndex:7158; appliedIndex:7157; }","duration":"194.667267ms","start":"2026-04-20T14:28:29.925192Z","end":"2026-04-20T14:28:30.11986Z","steps":["trace[1884793571] 'read index received' (duration: 194.469733ms)","trace[1884793571] 'applied index is now lower than readState.Index' (duration: 196.624µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:30.296173Z","caller":"traceutil/trace.go:171","msg":"trace[960974523] transaction","detail":"{read_only:false; response_revision:6907; number_of_response:1; }","duration":"171.065997ms","start":"2026-04-20T14:28:30.125084Z","end":"2026-04-20T14:28:30.29615Z","steps":["trace[960974523] 'process raft request' (duration: 171.006456ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:30.296221Z","caller":"traceutil/trace.go:171","msg":"trace[568204472] linearizableReadLoop","detail":"{readStateIndex:7159; appliedIndex:7158; }","duration":"174.82048ms","start":"2026-04-20T14:28:30.121382Z","end":"2026-04-20T14:28:30.296203Z","steps":["trace[568204472] 'read index received' (duration: 80.285207ms)","trace[568204472] 'applied index is now lower than readState.Index' (duration: 94.534313ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:30.296309Z","caller":"traceutil/trace.go:171","msg":"trace[922997596] transaction","detail":"{read_only:false; response_revision:6906; number_of_response:1; }","duration":"234.113346ms","start":"2026-04-20T14:28:30.062186Z","end":"2026-04-20T14:28:30.296299Z","steps":["trace[922997596] 'process raft request' (duration: 139.508741ms)","trace[922997596] 'compare' (duration: 93.965903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:30.296567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.84611ms","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-20T14:28:30.296971Z","caller":"traceutil/trace.go:171","msg":"trace[575123757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6907; }","duration":"200.310478ms","start":"2026-04-20T14:28:30.096649Z","end":"2026-04-20T14:28:30.29696Z","steps":["trace[575123757] 'agreement among raft nodes before linearized reading' (duration: 199.793647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:30.296979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.662756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-20T14:28:30.297009Z","caller":"traceutil/trace.go:171","msg":"trace[1659291163] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6907; }","duration":"116.723158ms","start":"2026-04-20T14:28:30.180276Z","end":"2026-04-20T14:28:30.296999Z","steps":["trace[1659291163] 'agreement among raft nodes before linearized reading' (duration: 116.651286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:30.296695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.289415ms","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-20T14:28:30.297095Z","caller":"traceutil/trace.go:171","msg":"trace[2021075681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6907; }","duration":"370.724353ms","start":"2026-04-20T14:28:29.926362Z","end":"2026-04-20T14:28:30.297087Z","steps":["trace[2021075681] 'agreement among raft nodes before linearized reading' (duration: 370.267005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:30.297124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:29.926348Z","time spent":"370.765434ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:28:30.296781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.989879ms","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-20T14:28:30.297239Z","caller":"traceutil/trace.go:171","msg":"trace[484079035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6907; }","duration":"226.486769ms","start":"2026-04-20T14:28:30.070745Z","end":"2026-04-20T14:28:30.297232Z","steps":["trace[484079035] 'agreement among raft nodes before linearized reading' (duration: 225.970049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:30.296838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.737153ms","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-20T14:28:30.297361Z","caller":"traceutil/trace.go:171","msg":"trace[1375827047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6907; }","duration":"227.286374ms","start":"2026-04-20T14:28:30.070066Z","end":"2026-04-20T14:28:30.297352Z","steps":["trace[1375827047] 'agreement among raft nodes before linearized reading' (duration: 226.729214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:29:58.868837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5445} {"level":"info","ts":"2026-04-20T14:29:58.976429Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5445,"took":"105.367008ms","hash":3784178505,"current-db-size-bytes":53522432,"current-db-size":"54 MB","current-db-size-in-use-bytes":18464768,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T14:29:58.976495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3784178505,"revision":5445,"compact-revision":2879}