{"level":"warn","ts":"2026-09-23T10:36:04.116893Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-23T10:36:04.117023Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.32: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://162.253.55.32:2380","--initial-cluster=instance=https://162.253.55.32:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.32:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.32: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-09-23T10:36:04.11711Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-23T10:36:04.117121Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-09-23T10:36:04.117155Z","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-09-23T10:36:04.117981Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"]} {"level":"info","ts":"2026-09-23T10:36:04.118091Z","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":16,"max-cpu-available":16,"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://162.253.55.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.32: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-09-23T10:36:04.121518Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.165288ms"} {"level":"info","ts":"2026-09-23T10:36:04.130463Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c665390cd9eacc14","cluster-id":"d19b610fbe64baae"} {"level":"info","ts":"2026-09-23T10:36:04.130672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=()"} {"level":"info","ts":"2026-09-23T10:36:04.130732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 0"} {"level":"info","ts":"2026-09-23T10:36:04.130758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c665390cd9eacc14 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-23T10:36:04.130781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 1"} {"level":"info","ts":"2026-09-23T10:36:04.130873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"warn","ts":"2026-09-23T10:36:04.137828Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-23T10:36:04.141156Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-23T10:36:04.143421Z","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-09-23T10:36:04.146078Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c665390cd9eacc14","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-23T10:36:04.147382Z","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-09-23T10:36:04.147634Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c665390cd9eacc14","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-23T10:36:04.147751Z","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-09-23T10:36:04.147787Z","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-09-23T10:36:04.148979Z","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-09-23T10:36:04.149101Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-09-23T10:36:04.149185Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-09-23T10:36:04.149364Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c665390cd9eacc14","initial-advertise-peer-urls":["https://162.253.55.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-23T10:36:04.14943Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-23T10:36:04.149453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"info","ts":"2026-09-23T10:36:04.14964Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","added-peer-id":"c665390cd9eacc14","added-peer-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-09-23T10:36:04.632104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 is starting a new election at term 1"} {"level":"info","ts":"2026-09-23T10:36:04.632253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-23T10:36:04.632313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgPreVoteResp from c665390cd9eacc14 at term 1"} {"level":"info","ts":"2026-09-23T10:36:04.632362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became candidate at term 2"} {"level":"info","ts":"2026-09-23T10:36:04.632405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgVoteResp from c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-09-23T10:36:04.632445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became leader at term 2"} {"level":"info","ts":"2026-09-23T10:36:04.632479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c665390cd9eacc14 elected leader c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-09-23T10:36:04.633951Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:36:04.634983Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T10:36:04.634988Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c665390cd9eacc14","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.32:2379]}","request-path":"/0/members/c665390cd9eacc14/attributes","cluster-id":"d19b610fbe64baae","publish-timeout":"7s"} {"level":"info","ts":"2026-09-23T10:36:04.635078Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T10:36:04.635382Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-23T10:36:04.635421Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-23T10:36:04.635673Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:36:04.635832Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:36:04.6359Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:36:04.63969Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.32:2379"} {"level":"info","ts":"2026-09-23T10:36:04.639713Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-23T10:36:54.788433Z","caller":"traceutil/trace.go:171","msg":"trace[1545190624] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"163.663722ms","start":"2026-09-23T10:36:54.624734Z","end":"2026-09-23T10:36:54.788398Z","steps":["trace[1545190624] 'process raft request' (duration: 95.316507ms)","trace[1545190624] 'compare' (duration: 68.170401ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:36:54.812596Z","caller":"traceutil/trace.go:171","msg":"trace[1108363972] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"186.344338ms","start":"2026-09-23T10:36:54.626196Z","end":"2026-09-23T10:36:54.81254Z","steps":["trace[1108363972] 'process raft request' (duration: 185.920987ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:36:54.8134Z","caller":"traceutil/trace.go:171","msg":"trace[2128545290] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"125.125436ms","start":"2026-09-23T10:36:54.688238Z","end":"2026-09-23T10:36:54.813363Z","steps":["trace[2128545290] 'process raft request' (duration: 124.392427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:36:55.306493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.348138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:36:55.306711Z","caller":"traceutil/trace.go:171","msg":"trace[410472754] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"319.463792ms","start":"2026-09-23T10:36:54.987196Z","end":"2026-09-23T10:36:55.30666Z","steps":["trace[410472754] 'read index received' (duration: 118.311032ms)","trace[410472754] 'applied index is now lower than readState.Index' (duration: 201.15084ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:36:55.306775Z","caller":"traceutil/trace.go:171","msg":"trace[1176081093] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"424.989936ms","start":"2026-09-23T10:36:54.881741Z","end":"2026-09-23T10:36:55.306731Z","steps":["trace[1176081093] 'process raft request' (duration: 223.771554ms)","trace[1176081093] 'compare' (duration: 200.093281ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:36:55.306915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.719869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:36:55.306963Z","caller":"traceutil/trace.go:171","msg":"trace[1398201476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"319.825812ms","start":"2026-09-23T10:36:54.987121Z","end":"2026-09-23T10:36:55.306947Z","steps":["trace[1398201476] 'agreement among raft nodes before linearized reading' (duration: 319.702538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:36:55.307008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:36:54.987086Z","time spent":"319.909494ms","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T10:36:55.307151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:36:54.881706Z","time spent":"425.259373ms","remote":"127.0.0.1:57202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-23T10:36:55.307426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.798247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vhfzj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-23T10:36:55.307548Z","caller":"traceutil/trace.go:171","msg":"trace[739321455] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vhfzj; range_end:; response_count:1; response_revision:655; }","duration":"304.002822ms","start":"2026-09-23T10:36:55.003521Z","end":"2026-09-23T10:36:55.307524Z","steps":["trace[739321455] 'agreement among raft nodes before linearized reading' (duration: 303.687294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:36:55.307593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:36:55.003491Z","time spent":"304.092885ms","remote":"127.0.0.1:41664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-vhfzj\" "} {"level":"warn","ts":"2026-09-23T10:36:55.307444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.99645ms","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-09-23T10:36:55.307643Z","caller":"traceutil/trace.go:171","msg":"trace[386846920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"266.257277ms","start":"2026-09-23T10:36:55.04137Z","end":"2026-09-23T10:36:55.307627Z","steps":["trace[386846920] 'agreement among raft nodes before linearized reading' (duration: 265.869827ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:30.730258Z","caller":"traceutil/trace.go:171","msg":"trace[2119427515] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"167.937013ms","start":"2026-09-23T10:38:30.562291Z","end":"2026-09-23T10:38:30.730228Z","steps":["trace[2119427515] 'read index received' (duration: 167.729717ms)","trace[2119427515] 'applied index is now lower than readState.Index' (duration: 206.116µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:38:30.730351Z","caller":"traceutil/trace.go:171","msg":"trace[529711893] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"169.63138ms","start":"2026-09-23T10:38:30.560673Z","end":"2026-09-23T10:38:30.730304Z","steps":["trace[529711893] 'process raft request' (duration: 169.360933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:38:30.730631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.295894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-23T10:38:30.730743Z","caller":"traceutil/trace.go:171","msg":"trace[1615513159] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1910; }","duration":"168.445828ms","start":"2026-09-23T10:38:30.562271Z","end":"2026-09-23T10:38:30.730717Z","steps":["trace[1615513159] 'agreement among raft nodes before linearized reading' (duration: 168.104738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:38:30.751582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.472452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:38:30.751704Z","caller":"traceutil/trace.go:171","msg":"trace[49510082] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1911; }","duration":"145.674097ms","start":"2026-09-23T10:38:30.606009Z","end":"2026-09-23T10:38:30.751683Z","steps":["trace[49510082] 'agreement among raft nodes before linearized reading' (duration: 145.461301ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:38.755384Z","caller":"traceutil/trace.go:171","msg":"trace[202051235] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"118.040684ms","start":"2026-09-23T10:38:38.637302Z","end":"2026-09-23T10:38:38.755342Z","steps":["trace[202051235] 'read index received' (duration: 66.520309ms)","trace[202051235] 'applied index is now lower than readState.Index' (duration: 51.518625ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:38:38.755473Z","caller":"traceutil/trace.go:171","msg":"trace[1038065511] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"125.338586ms","start":"2026-09-23T10:38:38.630083Z","end":"2026-09-23T10:38:38.755421Z","steps":["trace[1038065511] 'process raft request' (duration: 73.843392ms)","trace[1038065511] 'compare' (duration: 51.254997ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:38:38.755862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.531028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-23T10:38:38.75599Z","caller":"traceutil/trace.go:171","msg":"trace[893398415] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1993; }","duration":"118.686552ms","start":"2026-09-23T10:38:38.637276Z","end":"2026-09-23T10:38:38.755963Z","steps":["trace[893398415] 'agreement among raft nodes before linearized reading' (duration: 118.24256ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:39:20.321044Z","caller":"traceutil/trace.go:171","msg":"trace[1366094088] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"109.467437ms","start":"2026-09-23T10:39:20.21153Z","end":"2026-09-23T10:39:20.320998Z","steps":["trace[1366094088] 'process raft request' (duration: 109.112107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:39:20.504334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.802071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:39:20.50457Z","caller":"traceutil/trace.go:171","msg":"trace[1799271657] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"178.241649ms","start":"2026-09-23T10:39:20.326307Z","end":"2026-09-23T10:39:20.504548Z","steps":["trace[1799271657] 'process raft request' (duration: 178.152796ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:39:20.504612Z","caller":"traceutil/trace.go:171","msg":"trace[402615157] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"202.242562ms","start":"2026-09-23T10:39:20.302318Z","end":"2026-09-23T10:39:20.504561Z","steps":["trace[402615157] 'process raft request' (duration: 55.903236ms)","trace[402615157] 'compare' (duration: 145.644757ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:39:20.504745Z","caller":"traceutil/trace.go:171","msg":"trace[1588025781] linearizableReadLoop","detail":"{readStateIndex:2323; appliedIndex:2322; }","duration":"183.794292ms","start":"2026-09-23T10:39:20.320858Z","end":"2026-09-23T10:39:20.504707Z","steps":["trace[1588025781] 'read index received' (duration: 37.281831ms)","trace[1588025781] 'applied index is now lower than readState.Index' (duration: 146.508801ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:39:20.50492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.566467ms","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-09-23T10:39:20.504972Z","caller":"traceutil/trace.go:171","msg":"trace[681406960] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2238; }","duration":"208.646249ms","start":"2026-09-23T10:39:20.296306Z","end":"2026-09-23T10:39:20.504952Z","steps":["trace[681406960] 'agreement among raft nodes before linearized reading' (duration: 208.528526ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:39:20.511182Z","caller":"traceutil/trace.go:171","msg":"trace[1325307591] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"141.04141ms","start":"2026-09-23T10:39:20.370096Z","end":"2026-09-23T10:39:20.511137Z","steps":["trace[1325307591] 'process raft request' (duration: 140.789673ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:39:20.511368Z","caller":"traceutil/trace.go:171","msg":"trace[1607960279] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"114.720462ms","start":"2026-09-23T10:39:20.396584Z","end":"2026-09-23T10:39:20.511304Z","steps":["trace[1607960279] 'process raft request' (duration: 114.492545ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:40:14.382151Z","caller":"traceutil/trace.go:171","msg":"trace[391566381] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"156.050554ms","start":"2026-09-23T10:40:14.226072Z","end":"2026-09-23T10:40:14.382122Z","steps":["trace[391566381] 'process raft request' (duration: 155.783947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:40:50.66907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.896865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:40:50.669149Z","caller":"traceutil/trace.go:171","msg":"trace[1790526294] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2922; }","duration":"201.08201ms","start":"2026-09-23T10:40:50.468049Z","end":"2026-09-23T10:40:50.669131Z","steps":["trace[1790526294] 'count revisions from in-memory index tree' (duration: 200.744181ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:34.113595Z","caller":"traceutil/trace.go:171","msg":"trace[1403411773] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"129.253764ms","start":"2026-09-23T10:45:33.983487Z","end":"2026-09-23T10:45:34.112741Z","steps":["trace[1403411773] 'process raft request' (duration: 129.004097ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:34.134183Z","caller":"traceutil/trace.go:171","msg":"trace[691421163] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5313; }","duration":"145.240896ms","start":"2026-09-23T10:45:33.988918Z","end":"2026-09-23T10:45:34.134159Z","steps":["trace[691421163] 'read index received' (duration: 123.596778ms)","trace[691421163] 'applied index is now lower than readState.Index' (duration: 21.643178ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:45:34.134265Z","caller":"traceutil/trace.go:171","msg":"trace[2096853155] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"147.302723ms","start":"2026-09-23T10:45:33.986953Z","end":"2026-09-23T10:45:34.134256Z","steps":["trace[2096853155] 'process raft request' (duration: 147.149659ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:34.134192Z","caller":"traceutil/trace.go:171","msg":"trace[570495239] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"149.613716ms","start":"2026-09-23T10:45:33.984548Z","end":"2026-09-23T10:45:34.134161Z","steps":["trace[570495239] 'process raft request' (duration: 148.079644ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:34.134332Z","caller":"traceutil/trace.go:171","msg":"trace[565546910] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"149.647718ms","start":"2026-09-23T10:45:33.984634Z","end":"2026-09-23T10:45:34.134282Z","steps":["trace[565546910] 'process raft request' (duration: 149.414721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:45:34.134411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.475882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:45:34.134486Z","caller":"traceutil/trace.go:171","msg":"trace[258496070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5119; }","duration":"145.598846ms","start":"2026-09-23T10:45:33.988871Z","end":"2026-09-23T10:45:34.13447Z","steps":["trace[258496070] 'agreement among raft nodes before linearized reading' (duration: 145.441792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:45:34.134543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.453802ms","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-09-23T10:45:34.134611Z","caller":"traceutil/trace.go:171","msg":"trace[1783881092] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5119; }","duration":"145.538034ms","start":"2026-09-23T10:45:33.989055Z","end":"2026-09-23T10:45:34.134593Z","steps":["trace[1783881092] 'agreement among raft nodes before linearized reading' (duration: 145.322638ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:41.203344Z","caller":"traceutil/trace.go:171","msg":"trace[1384687316] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"158.371019ms","start":"2026-09-23T10:45:41.044941Z","end":"2026-09-23T10:45:41.203312Z","steps":["trace[1384687316] 'process raft request' (duration: 158.182964ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:41.204501Z","caller":"traceutil/trace.go:171","msg":"trace[1695843795] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"119.104533ms","start":"2026-09-23T10:45:41.085369Z","end":"2026-09-23T10:45:41.204474Z","steps":["trace[1695843795] 'process raft request' (duration: 118.948379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:45:44.518638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.815303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:45:44.518954Z","caller":"traceutil/trace.go:171","msg":"trace[630921726] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5603; }","duration":"183.184605ms","start":"2026-09-23T10:45:44.335706Z","end":"2026-09-23T10:45:44.518891Z","steps":["trace[630921726] 'read index received' (duration: 11.987201ms)","trace[630921726] 'applied index is now lower than readState.Index' (duration: 171.195674ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:45:44.518976Z","caller":"traceutil/trace.go:171","msg":"trace[364360359] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"277.855173ms","start":"2026-09-23T10:45:44.241056Z","end":"2026-09-23T10:45:44.518911Z","steps":["trace[364360359] 'process raft request' (duration: 277.74009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:45:44.51917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.436062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:45:44.519272Z","caller":"traceutil/trace.go:171","msg":"trace[1412879392] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5397; }","duration":"183.603076ms","start":"2026-09-23T10:45:44.335646Z","end":"2026-09-23T10:45:44.519249Z","steps":["trace[1412879392] 'agreement among raft nodes before linearized reading' (duration: 183.415381ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:44.518881Z","caller":"traceutil/trace.go:171","msg":"trace[699200596] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"283.044436ms","start":"2026-09-23T10:45:44.23581Z","end":"2026-09-23T10:45:44.518854Z","steps":["trace[699200596] 'process raft request' (duration: 111.864393ms)","trace[699200596] 'compare' (duration: 170.648159ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:45:59.843737Z","caller":"traceutil/trace.go:171","msg":"trace[1393097237] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"118.817625ms","start":"2026-09-23T10:45:59.724891Z","end":"2026-09-23T10:45:59.843708Z","steps":["trace[1393097237] 'process raft request' (duration: 72.852494ms)","trace[1393097237] 'compare' (duration: 44.726297ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:46:00.268801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.811229ms","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-09-23T10:46:00.268883Z","caller":"traceutil/trace.go:171","msg":"trace[519299567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5675; }","duration":"257.960593ms","start":"2026-09-23T10:46:00.010903Z","end":"2026-09-23T10:46:00.268863Z","steps":["trace[519299567] 'range keys from in-memory index tree' (duration: 257.629214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:46:00.269004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.34814ms","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-09-23T10:46:00.269101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.891845ms","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-09-23T10:46:00.269113Z","caller":"traceutil/trace.go:171","msg":"trace[329301388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5675; }","duration":"302.538046ms","start":"2026-09-23T10:45:59.966556Z","end":"2026-09-23T10:46:00.269094Z","steps":["trace[329301388] 'range keys from in-memory index tree' (duration: 302.090243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:46:00.269161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:45:59.966525Z","time spent":"302.625698ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-23T10:46:00.269159Z","caller":"traceutil/trace.go:171","msg":"trace[2143239008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5675; }","duration":"251.992348ms","start":"2026-09-23T10:46:00.01715Z","end":"2026-09-23T10:46:00.269142Z","steps":["trace[2143239008] 'range keys from in-memory index tree' (duration: 251.721051ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:04.673427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2997} {"level":"info","ts":"2026-09-23T10:46:04.75562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2997,"took":"78.527072ms","hash":524696368,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":31723520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T10:46:04.755696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":524696368,"revision":2997,"compact-revision":-1} {"level":"info","ts":"2026-09-23T10:46:08.354221Z","caller":"traceutil/trace.go:171","msg":"trace[360399311] transaction","detail":"{read_only:false; response_revision:5841; number_of_response:1; }","duration":"169.98557ms","start":"2026-09-23T10:46:08.18418Z","end":"2026-09-23T10:46:08.354165Z","steps":["trace[360399311] 'process raft request' (duration: 111.724069ms)","trace[360399311] 'compare' (duration: 56.901263ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:46:08.546906Z","caller":"traceutil/trace.go:171","msg":"trace[372278005] linearizableReadLoop","detail":"{readStateIndex:6065; appliedIndex:6064; }","duration":"132.603577ms","start":"2026-09-23T10:46:08.414278Z","end":"2026-09-23T10:46:08.546882Z","steps":["trace[372278005] 'read index received' (duration: 132.36026ms)","trace[372278005] 'applied index is now lower than readState.Index' (duration: 241.997µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:46:08.547047Z","caller":"traceutil/trace.go:171","msg":"trace[1136017018] transaction","detail":"{read_only:false; response_revision:5842; number_of_response:1; }","duration":"178.935068ms","start":"2026-09-23T10:46:08.3681Z","end":"2026-09-23T10:46:08.547035Z","steps":["trace[1136017018] 'process raft request' (duration: 178.62553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:46:08.54728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.981067ms","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-09-23T10:46:08.547319Z","caller":"traceutil/trace.go:171","msg":"trace[1073086563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5842; }","duration":"133.052009ms","start":"2026-09-23T10:46:08.414258Z","end":"2026-09-23T10:46:08.54731Z","steps":["trace[1073086563] 'agreement among raft nodes before linearized reading' (duration: 132.907255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:46:08.547596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.286184ms","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-09-23T10:46:08.547699Z","caller":"traceutil/trace.go:171","msg":"trace[2074640937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5842; }","duration":"117.407287ms","start":"2026-09-23T10:46:08.430266Z","end":"2026-09-23T10:46:08.547674Z","steps":["trace[2074640937] 'agreement among raft nodes before linearized reading' (duration: 117.198581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:46:08.547597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.360713ms","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-09-23T10:46:08.547891Z","caller":"traceutil/trace.go:171","msg":"trace[162320460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5842; }","duration":"118.675822ms","start":"2026-09-23T10:46:08.429189Z","end":"2026-09-23T10:46:08.547864Z","steps":["trace[162320460] 'agreement among raft nodes before linearized reading' (duration: 118.080616ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:08.70533Z","caller":"traceutil/trace.go:171","msg":"trace[784233929] transaction","detail":"{read_only:false; response_revision:5845; number_of_response:1; }","duration":"151.18575ms","start":"2026-09-23T10:46:08.554118Z","end":"2026-09-23T10:46:08.705304Z","steps":["trace[784233929] 'process raft request' (duration: 151.008025ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:08.705585Z","caller":"traceutil/trace.go:171","msg":"trace[1343149418] transaction","detail":"{read_only:false; response_revision:5844; number_of_response:1; }","duration":"151.939861ms","start":"2026-09-23T10:46:08.553633Z","end":"2026-09-23T10:46:08.705573Z","steps":["trace[1343149418] 'process raft request' (duration: 116.662725ms)","trace[1343149418] 'compare' (duration: 34.675359ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:46:08.705722Z","caller":"traceutil/trace.go:171","msg":"trace[1389179742] transaction","detail":"{read_only:false; response_revision:5851; number_of_response:1; }","duration":"147.539649ms","start":"2026-09-23T10:46:08.55816Z","end":"2026-09-23T10:46:08.7057Z","steps":["trace[1389179742] 'process raft request' (duration: 147.267262ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:08.705747Z","caller":"traceutil/trace.go:171","msg":"trace[905984328] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"151.475258ms","start":"2026-09-23T10:46:08.554248Z","end":"2026-09-23T10:46:08.705723Z","steps":["trace[905984328] 'process raft request' (duration: 150.941423ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:08.705805Z","caller":"traceutil/trace.go:171","msg":"trace[337237722] linearizableReadLoop","detail":"{readStateIndex:6067; appliedIndex:6065; }","duration":"151.860159ms","start":"2026-09-23T10:46:08.553905Z","end":"2026-09-23T10:46:08.705765Z","steps":["trace[337237722] 'read index received' (duration: 3.825066ms)","trace[337237722] 'applied index is now lower than readState.Index' (duration: 148.027703ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:46:08.705965Z","caller":"traceutil/trace.go:171","msg":"trace[1838549823] transaction","detail":"{read_only:false; response_revision:5847; number_of_response:1; }","duration":"151.664453ms","start":"2026-09-23T10:46:08.554289Z","end":"2026-09-23T10:46:08.705954Z","steps":["trace[1838549823] 'process raft request' (duration: 150.955254ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:08.705971Z","caller":"traceutil/trace.go:171","msg":"trace[201952255] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"149.891704ms","start":"2026-09-23T10:46:08.556068Z","end":"2026-09-23T10:46:08.70596Z","steps":["trace[201952255] 'process raft request' (duration: 149.297438ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:08.706069Z","caller":"traceutil/trace.go:171","msg":"trace[1031021764] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"150.639895ms","start":"2026-09-23T10:46:08.555393Z","end":"2026-09-23T10:46:08.706033Z","steps":["trace[1031021764] 'process raft request' (duration: 149.925586ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:46:08.706212Z","caller":"traceutil/trace.go:171","msg":"trace[819217242] transaction","detail":"{read_only:false; response_revision:5848; number_of_response:1; }","duration":"151.764646ms","start":"2026-09-23T10:46:08.554434Z","end":"2026-09-23T10:46:08.706198Z","steps":["trace[819217242] 'process raft request' (duration: 150.846341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:46:08.70636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.364973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d990d1df-1443-46be-8952-99cdf39ee376\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-23T10:46:08.706414Z","caller":"traceutil/trace.go:171","msg":"trace[711750196] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d990d1df-1443-46be-8952-99cdf39ee376; range_end:; response_count:1; response_revision:5854; }","duration":"152.526357ms","start":"2026-09-23T10:46:08.553873Z","end":"2026-09-23T10:46:08.706399Z","steps":["trace[711750196] 'agreement among raft nodes before linearized reading' (duration: 152.094736ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:51:04.680635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5756} {"level":"info","ts":"2026-09-23T10:51:04.771153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5756,"took":"88.505785ms","hash":4109860698,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":27148288,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-23T10:51:04.771238Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4109860698,"revision":5756,"compact-revision":2997} {"level":"warn","ts":"2026-09-23T10:52:16.914453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.962459ms","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-09-23T10:52:16.91458Z","caller":"traceutil/trace.go:171","msg":"trace[2119977455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8680; }","duration":"138.148765ms","start":"2026-09-23T10:52:16.776407Z","end":"2026-09-23T10:52:16.914556Z","steps":["trace[2119977455] 'range keys from in-memory index tree' (duration: 137.770814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:52:16.914756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.532179ms","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-09-23T10:52:16.914883Z","caller":"traceutil/trace.go:171","msg":"trace[352952514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8680; }","duration":"271.724434ms","start":"2026-09-23T10:52:16.643133Z","end":"2026-09-23T10:52:16.914858Z","steps":["trace[352952514] 'range keys from in-memory index tree' (duration: 271.278251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:52:16.914965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.671595ms","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-09-23T10:52:16.915099Z","caller":"traceutil/trace.go:171","msg":"trace[282883708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8680; }","duration":"155.87017ms","start":"2026-09-23T10:52:16.759187Z","end":"2026-09-23T10:52:16.915058Z","steps":["trace[282883708] 'range keys from in-memory index tree' (duration: 155.444549ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:52:17.019422Z","caller":"traceutil/trace.go:171","msg":"trace[1630654871] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"100.135844ms","start":"2026-09-23T10:52:16.919253Z","end":"2026-09-23T10:52:17.019389Z","steps":["trace[1630654871] 'process raft request' (duration: 99.936458ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:52:17.031985Z","caller":"traceutil/trace.go:171","msg":"trace[1398981207] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"112.133322ms","start":"2026-09-23T10:52:16.91981Z","end":"2026-09-23T10:52:17.031943Z","steps":["trace[1398981207] 'process raft request' (duration: 112.020439ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:52:17.031975Z","caller":"traceutil/trace.go:171","msg":"trace[1868044092] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"112.137902ms","start":"2026-09-23T10:52:16.91978Z","end":"2026-09-23T10:52:17.031918Z","steps":["trace[1868044092] 'process raft request' (duration: 111.954607ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:52:55.959269Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242b.wal"} {"level":"info","ts":"2026-09-23T10:52:59.833522Z","caller":"traceutil/trace.go:171","msg":"trace[457746496] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"228.904841ms","start":"2026-09-23T10:52:59.604531Z","end":"2026-09-23T10:52:59.833436Z","steps":["trace[457746496] 'process raft request' (duration: 228.370286ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:08.432102Z","caller":"traceutil/trace.go:171","msg":"trace[1753969295] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"122.329711ms","start":"2026-09-23T10:53:08.30971Z","end":"2026-09-23T10:53:08.432039Z","steps":["trace[1753969295] 'process raft request' (duration: 115.152285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:08.65624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.581862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:53:08.656356Z","caller":"traceutil/trace.go:171","msg":"trace[2135943076] linearizableReadLoop","detail":"{readStateIndex:9337; appliedIndex:9336; }","duration":"147.865281ms","start":"2026-09-23T10:53:08.508463Z","end":"2026-09-23T10:53:08.656328Z","steps":["trace[2135943076] 'read index received' (duration: 46.089453ms)","trace[2135943076] 'applied index is now lower than readState.Index' (duration: 101.774048ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:53:08.656371Z","caller":"traceutil/trace.go:171","msg":"trace[1885021282] transaction","detail":"{read_only:false; response_revision:9011; number_of_response:1; }","duration":"190.850148ms","start":"2026-09-23T10:53:08.465499Z","end":"2026-09-23T10:53:08.656349Z","steps":["trace[1885021282] 'process raft request' (duration: 89.04949ms)","trace[1885021282] 'compare' (duration: 101.439489ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:53:08.656477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.001604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:53:08.656532Z","caller":"traceutil/trace.go:171","msg":"trace[1709871169] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:9011; }","duration":"148.061526ms","start":"2026-09-23T10:53:08.508453Z","end":"2026-09-23T10:53:08.656514Z","steps":["trace[1709871169] 'agreement among raft nodes before linearized reading' (duration: 147.968893ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:08.676295Z","caller":"traceutil/trace.go:171","msg":"trace[164950063] transaction","detail":"{read_only:false; response_revision:9012; number_of_response:1; }","duration":"163.046576ms","start":"2026-09-23T10:53:08.513227Z","end":"2026-09-23T10:53:08.676273Z","steps":["trace[164950063] 'process raft request' (duration: 162.786559ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:08.676303Z","caller":"traceutil/trace.go:171","msg":"trace[1363286900] transaction","detail":"{read_only:false; response_revision:9013; number_of_response:1; }","duration":"143.09893ms","start":"2026-09-23T10:53:08.533176Z","end":"2026-09-23T10:53:08.676275Z","steps":["trace[1363286900] 'process raft request' (duration: 143.001058ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:08.676513Z","caller":"traceutil/trace.go:171","msg":"trace[1141257106] transaction","detail":"{read_only:false; response_revision:9014; number_of_response:1; }","duration":"137.063084ms","start":"2026-09-23T10:53:08.539438Z","end":"2026-09-23T10:53:08.676501Z","steps":["trace[1141257106] 'process raft request' (duration: 136.788557ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:14.412526Z","caller":"traceutil/trace.go:171","msg":"trace[1337109044] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9077; }","duration":"102.474837ms","start":"2026-09-23T10:53:14.310017Z","end":"2026-09-23T10:53:14.412491Z","steps":["trace[1337109044] 'process raft request' (duration: 78.430278ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:14.661844Z","caller":"traceutil/trace.go:171","msg":"trace[1621843738] transaction","detail":"{read_only:false; response_revision:9081; number_of_response:1; }","duration":"183.206229ms","start":"2026-09-23T10:53:14.478613Z","end":"2026-09-23T10:53:14.66182Z","steps":["trace[1621843738] 'process raft request' (duration: 114.328763ms)","trace[1621843738] 'compare' (duration: 68.672121ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:53:14.661831Z","caller":"traceutil/trace.go:171","msg":"trace[2073756606] linearizableReadLoop","detail":"{readStateIndex:9410; appliedIndex:9409; }","duration":"182.215222ms","start":"2026-09-23T10:53:14.479589Z","end":"2026-09-23T10:53:14.661805Z","steps":["trace[2073756606] 'read index received' (duration: 113.240253ms)","trace[2073756606] 'applied index is now lower than readState.Index' (duration: 68.973939ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:53:14.662016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.400617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-09-23T10:53:14.662066Z","caller":"traceutil/trace.go:171","msg":"trace[1435141650] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:9081; }","duration":"182.479689ms","start":"2026-09-23T10:53:14.479571Z","end":"2026-09-23T10:53:14.66205Z","steps":["trace[1435141650] 'agreement among raft nodes before linearized reading' (duration: 182.309554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:14.662132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.06126ms","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-09-23T10:53:14.662197Z","caller":"traceutil/trace.go:171","msg":"trace[1185474147] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:9081; }","duration":"181.165283ms","start":"2026-09-23T10:53:14.481014Z","end":"2026-09-23T10:53:14.662179Z","steps":["trace[1185474147] 'agreement among raft nodes before linearized reading' (duration: 180.983388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:14.662261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.638524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:1 size:2415"} {"level":"info","ts":"2026-09-23T10:53:14.66231Z","caller":"traceutil/trace.go:171","msg":"trace[584928153] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:1; response_revision:9081; }","duration":"178.700875ms","start":"2026-09-23T10:53:14.483594Z","end":"2026-09-23T10:53:14.662295Z","steps":["trace[584928153] 'agreement among raft nodes before linearized reading' (duration: 178.590252ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:14.804618Z","caller":"traceutil/trace.go:171","msg":"trace[502210706] transaction","detail":"{read_only:false; response_revision:9082; number_of_response:1; }","duration":"117.145329ms","start":"2026-09-23T10:53:14.687422Z","end":"2026-09-23T10:53:14.804567Z","steps":["trace[502210706] 'process raft request' (duration: 92.271358ms)","trace[502210706] 'compare' (duration: 24.669485ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:53:15.039311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.743436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:53:15.039438Z","caller":"traceutil/trace.go:171","msg":"trace[1630904548] transaction","detail":"{read_only:false; response_revision:9083; number_of_response:1; }","duration":"344.702143ms","start":"2026-09-23T10:53:14.694723Z","end":"2026-09-23T10:53:15.039425Z","steps":["trace[1630904548] 'process raft request' (duration: 238.759311ms)","trace[1630904548] 'compare' (duration: 105.628873ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:53:15.039486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:53:14.694695Z","time spent":"344.773925ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:53:15.039855Z","caller":"traceutil/trace.go:171","msg":"trace[1422968243] transaction","detail":"{read_only:false; response_revision:9084; number_of_response:1; }","duration":"342.428601ms","start":"2026-09-23T10:53:14.697397Z","end":"2026-09-23T10:53:15.039826Z","steps":["trace[1422968243] 'process raft request' (duration: 341.987609ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:15.039934Z","caller":"traceutil/trace.go:171","msg":"trace[7709075] linearizableReadLoop","detail":"{readStateIndex:9412; appliedIndex:9410; }","duration":"343.392698ms","start":"2026-09-23T10:53:14.696524Z","end":"2026-09-23T10:53:15.039917Z","steps":["trace[7709075] 'read index received' (duration: 83.182319ms)","trace[7709075] 'applied index is now lower than readState.Index' (duration: 260.209548ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:53:15.040053Z","caller":"traceutil/trace.go:171","msg":"trace[155404622] transaction","detail":"{read_only:false; response_revision:9085; number_of_response:1; }","duration":"341.264689ms","start":"2026-09-23T10:53:14.698779Z","end":"2026-09-23T10:53:15.040044Z","steps":["trace[155404622] 'process raft request' (duration: 340.94538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:15.040104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:53:14.698771Z","time spent":"341.29943ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T10:53:15.040278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.747577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-23T10:53:15.040303Z","caller":"traceutil/trace.go:171","msg":"trace[1916919006] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:9085; }","duration":"343.802769ms","start":"2026-09-23T10:53:14.696494Z","end":"2026-09-23T10:53:15.040296Z","steps":["trace[1916919006] 'agreement among raft nodes before linearized reading' (duration: 343.693466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:15.040322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:53:14.696483Z","time spent":"343.834349ms","remote":"127.0.0.1:41674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1309,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-09-23T10:53:15.040404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:53:14.697385Z","time spent":"342.664607ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T10:53:15.040493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.556298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-09-23T10:53:15.040522Z","caller":"traceutil/trace.go:171","msg":"trace[1594384438] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:9085; }","duration":"329.582379ms","start":"2026-09-23T10:53:14.71093Z","end":"2026-09-23T10:53:15.040513Z","steps":["trace[1594384438] 'agreement among raft nodes before linearized reading' (duration: 329.508417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:15.040575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:53:14.710889Z","time spent":"329.671861ms","remote":"127.0.0.1:41582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3708,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-09-23T10:53:15.040855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.657158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fm6xq\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-23T10:53:15.04088Z","caller":"traceutil/trace.go:171","msg":"trace[569366094] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fm6xq; range_end:; response_count:1; response_revision:9085; }","duration":"311.71125ms","start":"2026-09-23T10:53:14.729162Z","end":"2026-09-23T10:53:15.040873Z","steps":["trace[569366094] 'agreement among raft nodes before linearized reading' (duration: 311.642078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:53:15.040897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:53:14.729147Z","time spent":"311.74628ms","remote":"127.0.0.1:41664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-fm6xq\" "} {"level":"info","ts":"2026-09-23T10:54:14.924365Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c665390cd9eacc14","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T10:54:14.932513Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-23T10:54:14.932951Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-23T10:54:39.403444Z","caller":"traceutil/trace.go:171","msg":"trace[1687035738] transaction","detail":"{read_only:false; response_revision:9875; number_of_response:1; }","duration":"181.639296ms","start":"2026-09-23T10:54:39.221776Z","end":"2026-09-23T10:54:39.403416Z","steps":["trace[1687035738] 'process raft request' (duration: 181.464261ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:56:04.688814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8072} {"level":"info","ts":"2026-09-23T10:56:04.776092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8072,"took":"84.358153ms","hash":2007477780,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":24965120,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-23T10:56:04.776199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2007477780,"revision":8072,"compact-revision":5756} {"level":"info","ts":"2026-09-23T11:00:12.034521Z","caller":"traceutil/trace.go:171","msg":"trace[1110902174] transaction","detail":"{read_only:false; response_revision:12273; number_of_response:1; }","duration":"101.777413ms","start":"2026-09-23T11:00:11.932707Z","end":"2026-09-23T11:00:12.034485Z","steps":["trace[1110902174] 'process raft request' (duration: 101.635209ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:00:12.034619Z","caller":"traceutil/trace.go:171","msg":"trace[1497707752] transaction","detail":"{read_only:false; response_revision:12274; number_of_response:1; }","duration":"101.490024ms","start":"2026-09-23T11:00:11.933092Z","end":"2026-09-23T11:00:12.034582Z","steps":["trace[1497707752] 'process raft request' (duration: 101.32104ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:00:12.034935Z","caller":"traceutil/trace.go:171","msg":"trace[72131356] transaction","detail":"{read_only:false; response_revision:12272; number_of_response:1; }","duration":"102.529833ms","start":"2026-09-23T11:00:11.932389Z","end":"2026-09-23T11:00:12.034919Z","steps":["trace[72131356] 'process raft request' (duration: 99.448008ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:01:04.696685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10467} {"level":"info","ts":"2026-09-23T11:01:04.788356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10467,"took":"89.032282ms","hash":3122902169,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":25587712,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-23T11:01:04.788427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3122902169,"revision":10467,"compact-revision":8072} {"level":"info","ts":"2026-09-23T11:06:04.704621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12605} {"level":"info","ts":"2026-09-23T11:06:04.801845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12605,"took":"93.952821ms","hash":3228689677,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":27873280,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T11:06:04.801939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3228689677,"revision":12605,"compact-revision":10467} {"level":"warn","ts":"2026-09-23T11:06:46.595734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.132683ms","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-09-23T11:06:46.595831Z","caller":"traceutil/trace.go:171","msg":"trace[225673718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14934; }","duration":"158.277537ms","start":"2026-09-23T11:06:46.437531Z","end":"2026-09-23T11:06:46.595808Z","steps":["trace[225673718] 'range keys from in-memory index tree' (duration: 157.916407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:06:56.579051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.680533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-23T11:06:56.579132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.445062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fm6xq\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-23T11:06:56.579218Z","caller":"traceutil/trace.go:171","msg":"trace[765415544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14984; }","duration":"136.93182ms","start":"2026-09-23T11:06:56.442232Z","end":"2026-09-23T11:06:56.579164Z","steps":["trace[765415544] 'range keys from in-memory index tree' (duration: 136.406126ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:06:56.579307Z","caller":"traceutil/trace.go:171","msg":"trace[480591968] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fm6xq; range_end:; response_count:1; response_revision:14984; }","duration":"193.70262ms","start":"2026-09-23T11:06:56.385575Z","end":"2026-09-23T11:06:56.579278Z","steps":["trace[480591968] 'range keys from in-memory index tree' (duration: 193.069772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:07:05.936946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.619596ms","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-09-23T11:07:05.937087Z","caller":"traceutil/trace.go:171","msg":"trace[1525396185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15045; }","duration":"146.793941ms","start":"2026-09-23T11:07:05.790228Z","end":"2026-09-23T11:07:05.937022Z","steps":["trace[1525396185] 'range keys from in-memory index tree' (duration: 146.38772ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:07:06.201545Z","caller":"traceutil/trace.go:171","msg":"trace[1645479931] linearizableReadLoop","detail":"{readStateIndex:15575; appliedIndex:15574; }","duration":"140.454817ms","start":"2026-09-23T11:07:06.06105Z","end":"2026-09-23T11:07:06.201505Z","steps":["trace[1645479931] 'read index received' (duration: 140.062117ms)","trace[1645479931] 'applied index is now lower than readState.Index' (duration: 390.6µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:07:06.201619Z","caller":"traceutil/trace.go:171","msg":"trace[2140580134] transaction","detail":"{read_only:false; response_revision:15047; number_of_response:1; }","duration":"163.422317ms","start":"2026-09-23T11:07:06.038155Z","end":"2026-09-23T11:07:06.201577Z","steps":["trace[2140580134] 'process raft request' (duration: 163.051567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:07:06.20182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.759995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-crp24\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-23T11:07:06.20187Z","caller":"traceutil/trace.go:171","msg":"trace[1243322783] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-crp24; range_end:; response_count:1; response_revision:15047; }","duration":"140.879309ms","start":"2026-09-23T11:07:06.060979Z","end":"2026-09-23T11:07:06.201858Z","steps":["trace[1243322783] 'agreement among raft nodes before linearized reading' (duration: 140.711735ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:07:08.320877Z","caller":"traceutil/trace.go:171","msg":"trace[1586506715] range","detail":"{range_begin:/registry/masterleases/162.253.55.32; range_end:; response_count:1; response_revision:15065; }","duration":"100.041018ms","start":"2026-09-23T11:07:08.220798Z","end":"2026-09-23T11:07:08.320839Z","steps":["trace[1586506715] 'agreement among raft nodes before linearized reading' (duration: 99.76997ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:07:08.321096Z","caller":"traceutil/trace.go:171","msg":"trace[1085376470] transaction","detail":"{read_only:false; response_revision:15065; number_of_response:1; }","duration":"101.536388ms","start":"2026-09-23T11:07:08.219514Z","end":"2026-09-23T11:07:08.32105Z","steps":["trace[1085376470] 'process raft request' (duration: 100.211932ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:07:17.559813Z","caller":"traceutil/trace.go:171","msg":"trace[140340179] linearizableReadLoop","detail":"{readStateIndex:15759; appliedIndex:15758; }","duration":"134.098453ms","start":"2026-09-23T11:07:17.425684Z","end":"2026-09-23T11:07:17.559783Z","steps":["trace[140340179] 'read index received' (duration: 133.859626ms)","trace[140340179] 'applied index is now lower than readState.Index' (duration: 237.827µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:07:17.55989Z","caller":"traceutil/trace.go:171","msg":"trace[400975817] transaction","detail":"{read_only:false; response_revision:15228; number_of_response:1; }","duration":"220.67259ms","start":"2026-09-23T11:07:17.339186Z","end":"2026-09-23T11:07:17.559858Z","steps":["trace[400975817] 'process raft request' (duration: 220.414713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:07:17.560173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.465133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-ftc5l.18d7ee85eda81b96\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-23T11:07:17.560253Z","caller":"traceutil/trace.go:171","msg":"trace[1721876648] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-ftc5l.18d7ee85eda81b96; range_end:; response_count:1; response_revision:15228; }","duration":"134.630557ms","start":"2026-09-23T11:07:17.425607Z","end":"2026-09-23T11:07:17.560238Z","steps":["trace[1721876648] 'agreement among raft nodes before linearized reading' (duration: 134.308459ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:11:04.715958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14675} {"level":"info","ts":"2026-09-23T11:11:04.810727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14675,"took":"91.331089ms","hash":2936214208,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T11:11:04.810835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2936214208,"revision":14675,"compact-revision":12605} {"level":"info","ts":"2026-09-23T11:15:34.179808Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c665390cd9eacc14","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:15:34.197695Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-23T11:15:34.197808Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-23T11:16:04.724589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17182} {"level":"info","ts":"2026-09-23T11:16:04.831983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17182,"took":"103.258798ms","hash":3519045676,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":33923072,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-23T11:16:04.832064Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3519045676,"revision":17182,"compact-revision":14675} {"level":"info","ts":"2026-09-23T11:21:00.495901Z","caller":"traceutil/trace.go:171","msg":"trace[368461711] linearizableReadLoop","detail":"{readStateIndex:22425; appliedIndex:22424; }","duration":"188.1772ms","start":"2026-09-23T11:21:00.307693Z","end":"2026-09-23T11:21:00.49587Z","steps":["trace[368461711] 'read index received' (duration: 187.938994ms)","trace[368461711] 'applied index is now lower than readState.Index' (duration: 237.266µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:21:00.496219Z","caller":"traceutil/trace.go:171","msg":"trace[498788812] transaction","detail":"{read_only:false; response_revision:21680; number_of_response:1; }","duration":"197.2222ms","start":"2026-09-23T11:21:00.298983Z","end":"2026-09-23T11:21:00.496205Z","steps":["trace[498788812] 'process raft request' (duration: 196.726986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:21:00.496447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.700174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:21:00.496547Z","caller":"traceutil/trace.go:171","msg":"trace[1131733156] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:21680; }","duration":"188.90635ms","start":"2026-09-23T11:21:00.307618Z","end":"2026-09-23T11:21:00.496524Z","steps":["trace[1131733156] 'agreement among raft nodes before linearized reading' (duration: 188.667794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:21:00.549061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.445232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-23T11:21:00.549164Z","caller":"traceutil/trace.go:171","msg":"trace[901332931] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21681; }","duration":"120.618556ms","start":"2026-09-23T11:21:00.428522Z","end":"2026-09-23T11:21:00.549141Z","steps":["trace[901332931] 'agreement among raft nodes before linearized reading' (duration: 120.298847ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:21:00.549429Z","caller":"traceutil/trace.go:171","msg":"trace[340049355] transaction","detail":"{read_only:false; response_revision:21681; number_of_response:1; }","duration":"153.577796ms","start":"2026-09-23T11:21:00.395789Z","end":"2026-09-23T11:21:00.549367Z","steps":["trace[340049355] 'process raft request' (duration: 151.794106ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:21:04.732433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19686} {"level":"info","ts":"2026-09-23T11:21:04.859464Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19686,"took":"122.17356ms","hash":3876755553,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":29175808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-23T11:21:04.859557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3876755553,"revision":19686,"compact-revision":17182} {"level":"info","ts":"2026-09-23T11:21:16.035939Z","caller":"traceutil/trace.go:171","msg":"trace[1778219503] linearizableReadLoop","detail":"{readStateIndex:22509; appliedIndex:22508; }","duration":"124.990113ms","start":"2026-09-23T11:21:15.910913Z","end":"2026-09-23T11:21:16.035903Z","steps":["trace[1778219503] 'read index received' (duration: 124.216442ms)","trace[1778219503] 'applied index is now lower than readState.Index' (duration: 771.661µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:21:16.036146Z","caller":"traceutil/trace.go:171","msg":"trace[1574937712] transaction","detail":"{read_only:false; response_revision:21760; number_of_response:1; }","duration":"147.047683ms","start":"2026-09-23T11:21:15.889086Z","end":"2026-09-23T11:21:16.036134Z","steps":["trace[1574937712] 'process raft request' (duration: 146.144978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:21:16.036474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.527788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-23T11:21:16.036521Z","caller":"traceutil/trace.go:171","msg":"trace[1068990098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21760; }","duration":"125.623391ms","start":"2026-09-23T11:21:15.910887Z","end":"2026-09-23T11:21:16.036511Z","steps":["trace[1068990098] 'agreement among raft nodes before linearized reading' (duration: 125.419226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:21:16.036739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.650764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-23T11:21:16.036847Z","caller":"traceutil/trace.go:171","msg":"trace[1403115009] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21760; }","duration":"107.791718ms","start":"2026-09-23T11:21:15.929033Z","end":"2026-09-23T11:21:16.036825Z","steps":["trace[1403115009] 'agreement among raft nodes before linearized reading' (duration: 107.406347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:21:21.744989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.305187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:21:21.745118Z","caller":"traceutil/trace.go:171","msg":"trace[603865907] linearizableReadLoop","detail":"{readStateIndex:22538; appliedIndex:22537; }","duration":"293.017276ms","start":"2026-09-23T11:21:21.452083Z","end":"2026-09-23T11:21:21.745101Z","steps":["trace[603865907] 'read index received' (duration: 131.455392ms)","trace[603865907] 'applied index is now lower than readState.Index' (duration: 161.560244ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:21:21.745491Z","caller":"traceutil/trace.go:171","msg":"trace[839495308] transaction","detail":"{read_only:false; response_revision:21788; number_of_response:1; }","duration":"294.884348ms","start":"2026-09-23T11:21:21.450567Z","end":"2026-09-23T11:21:21.745451Z","steps":["trace[839495308] 'process raft request' (duration: 133.011045ms)","trace[839495308] 'compare' (duration: 160.665779ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:21:21.746154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.034845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-09-23T11:21:21.74625Z","caller":"traceutil/trace.go:171","msg":"trace[1088953115] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21788; }","duration":"294.187479ms","start":"2026-09-23T11:21:21.452039Z","end":"2026-09-23T11:21:21.746226Z","steps":["trace[1088953115] 'agreement among raft nodes before linearized reading' (duration: 293.289174ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:21:21.896635Z","caller":"traceutil/trace.go:171","msg":"trace[1187193433] transaction","detail":"{read_only:false; response_revision:21790; number_of_response:1; }","duration":"145.070019ms","start":"2026-09-23T11:21:21.751514Z","end":"2026-09-23T11:21:21.896584Z","steps":["trace[1187193433] 'process raft request' (duration: 136.311007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:21:22.187334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.081976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-23T11:21:22.18752Z","caller":"traceutil/trace.go:171","msg":"trace[403736238] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21790; }","duration":"121.415865ms","start":"2026-09-23T11:21:22.066075Z","end":"2026-09-23T11:21:22.18749Z","steps":["trace[403736238] 'range keys from in-memory index tree' (duration: 120.841929ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:21:22.341071Z","caller":"traceutil/trace.go:171","msg":"trace[1897408720] transaction","detail":"{read_only:false; response_revision:21791; number_of_response:1; }","duration":"148.487672ms","start":"2026-09-23T11:21:22.192544Z","end":"2026-09-23T11:21:22.341031Z","steps":["trace[1897408720] 'process raft request' (duration: 148.269766ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:21:22.368718Z","caller":"traceutil/trace.go:171","msg":"trace[1840751666] transaction","detail":"{read_only:false; response_revision:21792; number_of_response:1; }","duration":"176.055924ms","start":"2026-09-23T11:21:22.19263Z","end":"2026-09-23T11:21:22.368686Z","steps":["trace[1840751666] 'process raft request' (duration: 175.863888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:21:22.538793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.643317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-09-23T11:21:22.538941Z","caller":"traceutil/trace.go:171","msg":"trace[2015846400] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:21792; }","duration":"110.868964ms","start":"2026-09-23T11:21:22.428039Z","end":"2026-09-23T11:21:22.538908Z","steps":["trace[2015846400] 'range keys from in-memory index tree' (duration: 110.390791ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:21:22.764778Z","caller":"traceutil/trace.go:171","msg":"trace[936622558] transaction","detail":"{read_only:false; response_revision:21794; number_of_response:1; }","duration":"122.839754ms","start":"2026-09-23T11:21:22.641906Z","end":"2026-09-23T11:21:22.764746Z","steps":["trace[936622558] 'process raft request' (duration: 87.683493ms)","trace[936622558] 'compare' (duration: 34.969676ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:21:22.794796Z","caller":"traceutil/trace.go:171","msg":"trace[524768198] transaction","detail":"{read_only:false; response_revision:21795; number_of_response:1; }","duration":"104.121177ms","start":"2026-09-23T11:21:22.690632Z","end":"2026-09-23T11:21:22.794753Z","steps":["trace[524768198] 'process raft request' (duration: 103.87022ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:04.74131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21704} {"level":"info","ts":"2026-09-23T11:26:04.869422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21704,"took":"123.427951ms","hash":3661001438,"current-db-size-bytes":56074240,"current-db-size":"56 MB","current-db-size-in-use-bytes":41910272,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-23T11:26:04.869543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3661001438,"revision":21704,"compact-revision":19686} {"level":"info","ts":"2026-09-23T11:26:28.325532Z","caller":"traceutil/trace.go:171","msg":"trace[1690553129] linearizableReadLoop","detail":"{readStateIndex:25852; appliedIndex:25851; }","duration":"150.128328ms","start":"2026-09-23T11:26:28.175376Z","end":"2026-09-23T11:26:28.325504Z","steps":["trace[1690553129] 'read index received' (duration: 149.925922ms)","trace[1690553129] 'applied index is now lower than readState.Index' (duration: 200.886µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:28.325663Z","caller":"traceutil/trace.go:171","msg":"trace[374550420] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"158.739586ms","start":"2026-09-23T11:26:28.166912Z","end":"2026-09-23T11:26:28.325652Z","steps":["trace[374550420] 'process raft request' (duration: 158.358286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.325823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.437227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:28.326059Z","caller":"traceutil/trace.go:171","msg":"trace[1995664691] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:25011; }","duration":"150.717045ms","start":"2026-09-23T11:26:28.175327Z","end":"2026-09-23T11:26:28.326044Z","steps":["trace[1995664691] 'agreement among raft nodes before linearized reading' (duration: 150.441147ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:28.331467Z","caller":"traceutil/trace.go:171","msg":"trace[1351510793] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"145.253773ms","start":"2026-09-23T11:26:28.18619Z","end":"2026-09-23T11:26:28.331444Z","steps":["trace[1351510793] 'process raft request' (duration: 145.15142ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:28.331575Z","caller":"traceutil/trace.go:171","msg":"trace[796625703] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"154.735725ms","start":"2026-09-23T11:26:28.176791Z","end":"2026-09-23T11:26:28.331527Z","steps":["trace[796625703] 'process raft request' (duration: 154.409426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.331951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.202247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:28.332031Z","caller":"traceutil/trace.go:171","msg":"trace[1090241083] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:25014; }","duration":"156.286379ms","start":"2026-09-23T11:26:28.175721Z","end":"2026-09-23T11:26:28.332008Z","steps":["trace[1090241083] 'agreement among raft nodes before linearized reading' (duration: 156.189276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.332207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.729171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:28.332271Z","caller":"traceutil/trace.go:171","msg":"trace[1151716590] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:0; response_revision:25014; }","duration":"156.839443ms","start":"2026-09-23T11:26:28.175417Z","end":"2026-09-23T11:26:28.332256Z","steps":["trace[1151716590] 'agreement among raft nodes before linearized reading' (duration: 156.745771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.332372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.487886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:28.33243Z","caller":"traceutil/trace.go:171","msg":"trace[1389949990] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25014; }","duration":"155.559818ms","start":"2026-09-23T11:26:28.176856Z","end":"2026-09-23T11:26:28.332416Z","steps":["trace[1389949990] 'agreement among raft nodes before linearized reading' (duration: 155.461265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.332593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.297758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:28.33275Z","caller":"traceutil/trace.go:171","msg":"trace[557170122] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25014; }","duration":"156.358131ms","start":"2026-09-23T11:26:28.176274Z","end":"2026-09-23T11:26:28.332632Z","steps":["trace[557170122] 'agreement among raft nodes before linearized reading' (duration: 156.285879ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:45.303609Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a1c.wal"} {"level":"info","ts":"2026-09-23T11:28:49.150881Z","caller":"traceutil/trace.go:171","msg":"trace[1158713074] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"152.36991ms","start":"2026-09-23T11:28:48.99848Z","end":"2026-09-23T11:28:49.15085Z","steps":["trace[1158713074] 'process raft request' (duration: 152.153964ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:49.152155Z","caller":"traceutil/trace.go:171","msg":"trace[1343706670] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"152.058351ms","start":"2026-09-23T11:28:49.000026Z","end":"2026-09-23T11:28:49.152084Z","steps":["trace[1343706670] 'process raft request' (duration: 151.469315ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:54.721245Z","caller":"traceutil/trace.go:171","msg":"trace[111562415] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"181.410012ms","start":"2026-09-23T11:28:54.539772Z","end":"2026-09-23T11:28:54.721182Z","steps":["trace[111562415] 'process raft request' (duration: 180.917618ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:55.007447Z","caller":"traceutil/trace.go:171","msg":"trace[829725790] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"281.960521ms","start":"2026-09-23T11:28:54.72545Z","end":"2026-09-23T11:28:55.007411Z","steps":["trace[829725790] 'process raft request' (duration: 281.725764ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:55.086575Z","caller":"traceutil/trace.go:171","msg":"trace[720880930] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"360.386698ms","start":"2026-09-23T11:28:54.726144Z","end":"2026-09-23T11:28:55.08653Z","steps":["trace[720880930] 'process raft request' (duration: 357.673443ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:55.086606Z","caller":"traceutil/trace.go:171","msg":"trace[1297634352] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"359.335709ms","start":"2026-09-23T11:28:54.727222Z","end":"2026-09-23T11:28:55.086558Z","steps":["trace[1297634352] 'process raft request' (duration: 359.160494ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:55.086683Z","caller":"traceutil/trace.go:171","msg":"trace[99206871] linearizableReadLoop","detail":"{readStateIndex:27254; appliedIndex:27252; }","duration":"359.570625ms","start":"2026-09-23T11:28:54.727079Z","end":"2026-09-23T11:28:55.086649Z","steps":["trace[99206871] 'read index received' (duration: 280.144111ms)","trace[99206871] 'applied index is now lower than readState.Index' (duration: 79.425424ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:28:55.086727Z","caller":"traceutil/trace.go:171","msg":"trace[6178864] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"359.091943ms","start":"2026-09-23T11:28:54.727568Z","end":"2026-09-23T11:28:55.08666Z","steps":["trace[6178864] 'process raft request' (duration: 358.880897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:55.086803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:54.726115Z","time spent":"360.560513ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:28:55.086832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:54.727215Z","time spent":"359.495803ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1168,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:28:55.086946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:54.72756Z","time spent":"359.281448ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:28:55.087071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.772882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-09-23T11:28:55.086729Z","caller":"traceutil/trace.go:171","msg":"trace[919647658] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"353.976191ms","start":"2026-09-23T11:28:54.73272Z","end":"2026-09-23T11:28:55.086696Z","steps":["trace[919647658] 'process raft request' (duration: 353.809856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:55.087193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:54.732682Z","time spent":"354.461844ms","remote":"127.0.0.1:41914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:28:55.087156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.072089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-09-23T11:28:55.087268Z","caller":"traceutil/trace.go:171","msg":"trace[643477091] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:26382; }","duration":"360.206163ms","start":"2026-09-23T11:28:54.727046Z","end":"2026-09-23T11:28:55.087252Z","steps":["trace[643477091] 'agreement among raft nodes before linearized reading' (duration: 359.958296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:55.087316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:54.727035Z","time spent":"360.257944ms","remote":"127.0.0.1:41682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-09-23T11:28:55.08732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.775454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:28:55.087402Z","caller":"traceutil/trace.go:171","msg":"trace[1387964505] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26382; }","duration":"254.152593ms","start":"2026-09-23T11:28:54.833235Z","end":"2026-09-23T11:28:55.087387Z","steps":["trace[1387964505] 'agreement among raft nodes before linearized reading' (duration: 253.70636ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:55.087491Z","caller":"traceutil/trace.go:171","msg":"trace[1013470464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26382; }","duration":"101.01121ms","start":"2026-09-23T11:28:54.986443Z","end":"2026-09-23T11:28:55.087454Z","steps":["trace[1013470464] 'agreement among raft nodes before linearized reading' (duration: 100.671771ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:55.255575Z","caller":"traceutil/trace.go:171","msg":"trace[970129869] transaction","detail":"{read_only:false; response_revision:26383; number_of_response:1; }","duration":"150.506169ms","start":"2026-09-23T11:28:55.104945Z","end":"2026-09-23T11:28:55.255452Z","steps":["trace[970129869] 'process raft request' (duration: 150.233731ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:55.261167Z","caller":"traceutil/trace.go:171","msg":"trace[1369068585] transaction","detail":"{read_only:false; response_revision:26384; number_of_response:1; }","duration":"155.923769ms","start":"2026-09-23T11:28:55.10522Z","end":"2026-09-23T11:28:55.261144Z","steps":["trace[1369068585] 'process raft request' (duration: 155.535458ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:59.151783Z","caller":"traceutil/trace.go:171","msg":"trace[1478985383] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"112.743205ms","start":"2026-09-23T11:28:59.039009Z","end":"2026-09-23T11:28:59.151752Z","steps":["trace[1478985383] 'process raft request' (duration: 112.54464ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:59.287359Z","caller":"traceutil/trace.go:171","msg":"trace[634707215] linearizableReadLoop","detail":"{readStateIndex:27290; appliedIndex:27287; }","duration":"178.871372ms","start":"2026-09-23T11:28:59.10845Z","end":"2026-09-23T11:28:59.287321Z","steps":["trace[634707215] 'read index received' (duration: 43.163522ms)","trace[634707215] 'applied index is now lower than readState.Index' (duration: 135.70673ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:28:59.287398Z","caller":"traceutil/trace.go:171","msg":"trace[1292700133] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"229.46836ms","start":"2026-09-23T11:28:59.057886Z","end":"2026-09-23T11:28:59.287354Z","steps":["trace[1292700133] 'process raft request' (duration: 229.12403ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:59.287461Z","caller":"traceutil/trace.go:171","msg":"trace[1564736505] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"183.727327ms","start":"2026-09-23T11:28:59.103698Z","end":"2026-09-23T11:28:59.287425Z","steps":["trace[1564736505] 'process raft request' (duration: 183.561552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:59.287866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.203542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-23T11:28:59.287981Z","caller":"traceutil/trace.go:171","msg":"trace[330319995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26413; }","duration":"179.580642ms","start":"2026-09-23T11:28:59.108376Z","end":"2026-09-23T11:28:59.287957Z","steps":["trace[330319995] 'agreement among raft nodes before linearized reading' (duration: 179.088608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:59.588495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.997033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:28:59.58897Z","caller":"traceutil/trace.go:171","msg":"trace[1461423626] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"308.953487ms","start":"2026-09-23T11:28:59.279992Z","end":"2026-09-23T11:28:59.588945Z","steps":["trace[1461423626] 'process raft request' (duration: 308.855184ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:59.588997Z","caller":"traceutil/trace.go:171","msg":"trace[1298444496] transaction","detail":"{read_only:false; response_revision:26415; number_of_response:1; }","duration":"309.45254ms","start":"2026-09-23T11:28:59.279505Z","end":"2026-09-23T11:28:59.588957Z","steps":["trace[1298444496] 'process raft request' (duration: 309.131372ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:59.589127Z","caller":"traceutil/trace.go:171","msg":"trace[940118731] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"308.371461ms","start":"2026-09-23T11:28:59.280744Z","end":"2026-09-23T11:28:59.589115Z","steps":["trace[940118731] 'process raft request' (duration: 308.147455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:59.589189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:59.280732Z","time spent":"308.428512ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:28:59.588998Z","caller":"traceutil/trace.go:171","msg":"trace[742224541] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"311.987071ms","start":"2026-09-23T11:28:59.276956Z","end":"2026-09-23T11:28:59.588943Z","steps":["trace[742224541] 'process raft request' (duration: 173.386711ms)","trace[742224541] 'compare' (duration: 137.409507ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:28:59.589308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:59.279473Z","time spent":"309.680247ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:28:59.58906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:59.279958Z","time spent":"309.06505ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:28:59.589388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.743395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:28:59.589436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.953431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:28:59.589392Z","caller":"traceutil/trace.go:171","msg":"trace[218032499] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"297.12366ms","start":"2026-09-23T11:28:59.292235Z","end":"2026-09-23T11:28:59.589359Z","steps":["trace[218032499] 'process raft request' (duration: 296.939784ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:59.589481Z","caller":"traceutil/trace.go:171","msg":"trace[926978909] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26418; }","duration":"479.996652ms","start":"2026-09-23T11:28:59.109468Z","end":"2026-09-23T11:28:59.589464Z","steps":["trace[926978909] 'agreement among raft nodes before linearized reading' (duration: 479.818038ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:59.589484Z","caller":"traceutil/trace.go:171","msg":"trace[1838038625] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26418; }","duration":"344.90169ms","start":"2026-09-23T11:28:59.24456Z","end":"2026-09-23T11:28:59.589462Z","steps":["trace[1838038625] 'agreement among raft nodes before linearized reading' (duration: 344.688204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:59.589565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:59.109443Z","time spent":"480.111276ms","remote":"127.0.0.1:53968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:28:59.589572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:59.244528Z","time spent":"345.032343ms","remote":"127.0.0.1:46918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"info","ts":"2026-09-23T11:28:59.589246Z","caller":"traceutil/trace.go:171","msg":"trace[386531015] linearizableReadLoop","detail":"{readStateIndex:27294; appliedIndex:27290; }","duration":"301.763878ms","start":"2026-09-23T11:28:59.287454Z","end":"2026-09-23T11:28:59.589218Z","steps":["trace[386531015] 'read index received' (duration: 162.977433ms)","trace[386531015] 'applied index is now lower than readState.Index' (duration: 138.782425ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:28:59.58963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:28:59.276909Z","time spent":"312.404862ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:28:59.589471Z","caller":"traceutil/trace.go:171","msg":"trace[1273480905] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"246.735287ms","start":"2026-09-23T11:28:59.342714Z","end":"2026-09-23T11:28:59.589449Z","steps":["trace[1273480905] 'process raft request' (duration: 246.605803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:59.589923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.873756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:28:59.590003Z","caller":"traceutil/trace.go:171","msg":"trace[443775698] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26419; }","duration":"110.02867ms","start":"2026-09-23T11:28:59.479949Z","end":"2026-09-23T11:28:59.589978Z","steps":["trace[443775698] 'agreement among raft nodes before linearized reading' (duration: 109.877595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:59.58996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.055697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-23T11:28:59.590253Z","caller":"traceutil/trace.go:171","msg":"trace[1883845249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26419; }","duration":"251.417266ms","start":"2026-09-23T11:28:59.338807Z","end":"2026-09-23T11:28:59.590224Z","steps":["trace[1883845249] 'agreement among raft nodes before linearized reading' (duration: 250.945293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:59.817296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.860672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:28:59.817501Z","caller":"traceutil/trace.go:171","msg":"trace[1784390918] linearizableReadLoop","detail":"{readStateIndex:27298; appliedIndex:27296; }","duration":"215.561526ms","start":"2026-09-23T11:28:59.601906Z","end":"2026-09-23T11:28:59.817468Z","steps":["trace[1784390918] 'read index received' (duration: 74.386946ms)","trace[1784390918] 'applied index is now lower than readState.Index' (duration: 141.17315ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:28:59.817614Z","caller":"traceutil/trace.go:171","msg":"trace[1000880850] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"221.12816ms","start":"2026-09-23T11:28:59.596474Z","end":"2026-09-23T11:28:59.817602Z","steps":["trace[1000880850] 'process raft request' (duration: 117.847336ms)","trace[1000880850] 'compare' (duration: 102.713858ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:28:59.818189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.286236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-23T11:28:59.818231Z","caller":"traceutil/trace.go:171","msg":"trace[1200755355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26422; }","duration":"216.370688ms","start":"2026-09-23T11:28:59.601848Z","end":"2026-09-23T11:28:59.818218Z","steps":["trace[1200755355] 'agreement among raft nodes before linearized reading' (duration: 216.205494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:28:59.818429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.295289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:28:59.818462Z","caller":"traceutil/trace.go:171","msg":"trace[691643697] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:26422; }","duration":"143.332141ms","start":"2026-09-23T11:28:59.675122Z","end":"2026-09-23T11:28:59.818454Z","steps":["trace[691643697] 'agreement among raft nodes before linearized reading' (duration: 143.193777ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:00.013305Z","caller":"traceutil/trace.go:171","msg":"trace[236436036] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"188.126378ms","start":"2026-09-23T11:28:59.825147Z","end":"2026-09-23T11:29:00.013273Z","steps":["trace[236436036] 'process raft request' (duration: 160.646339ms)","trace[236436036] 'compare' (duration: 27.17279ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:00.01372Z","caller":"traceutil/trace.go:171","msg":"trace[71298943] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"129.92997ms","start":"2026-09-23T11:28:59.883757Z","end":"2026-09-23T11:29:00.013687Z","steps":["trace[71298943] 'process raft request' (duration: 129.465357ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:01.402804Z","caller":"traceutil/trace.go:171","msg":"trace[155948758] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"108.286733ms","start":"2026-09-23T11:29:01.29447Z","end":"2026-09-23T11:29:01.402757Z","steps":["trace[155948758] 'process raft request' (duration: 106.598796ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:01.943324Z","caller":"traceutil/trace.go:171","msg":"trace[1360002030] linearizableReadLoop","detail":"{readStateIndex:27305; appliedIndex:27304; }","duration":"346.017921ms","start":"2026-09-23T11:29:01.597275Z","end":"2026-09-23T11:29:01.943293Z","steps":["trace[1360002030] 'read index received' (duration: 345.807536ms)","trace[1360002030] 'applied index is now lower than readState.Index' (duration: 209.125µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:01.943397Z","caller":"traceutil/trace.go:171","msg":"trace[15066558] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"347.404559ms","start":"2026-09-23T11:29:01.595944Z","end":"2026-09-23T11:29:01.943348Z","steps":["trace[15066558] 'process raft request' (duration: 347.128011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:01.943615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.303679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-23T11:29:01.943652Z","caller":"traceutil/trace.go:171","msg":"trace[277973185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26428; }","duration":"346.401632ms","start":"2026-09-23T11:29:01.59724Z","end":"2026-09-23T11:29:01.943642Z","steps":["trace[277973185] 'agreement among raft nodes before linearized reading' (duration: 346.191936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:01.943678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.595901Z","time spent":"347.650185ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:01.943695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.597227Z","time spent":"346.456593ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-23T11:29:02.072331Z","caller":"traceutil/trace.go:171","msg":"trace[1640567433] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"474.947914ms","start":"2026-09-23T11:29:01.597345Z","end":"2026-09-23T11:29:02.072293Z","steps":["trace[1640567433] 'process raft request' (duration: 432.950313ms)","trace[1640567433] 'compare' (duration: 41.780625ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:02.072556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.597336Z","time spent":"475.085797ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:02.072614Z","caller":"traceutil/trace.go:171","msg":"trace[261317944] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"473.699199ms","start":"2026-09-23T11:29:01.598881Z","end":"2026-09-23T11:29:02.07258Z","steps":["trace[261317944] 'process raft request' (duration: 473.358549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.072832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.598847Z","time spent":"473.880794ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:02.072935Z","caller":"traceutil/trace.go:171","msg":"trace[1867404822] linearizableReadLoop","detail":"{readStateIndex:27309; appliedIndex:27305; }","duration":"129.509029ms","start":"2026-09-23T11:29:01.943405Z","end":"2026-09-23T11:29:02.072914Z","steps":["trace[1867404822] 'read index received' (duration: 86.908162ms)","trace[1867404822] 'applied index is now lower than readState.Index' (duration: 42.599637ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:02.072978Z","caller":"traceutil/trace.go:171","msg":"trace[1948339561] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"240.759933ms","start":"2026-09-23T11:29:01.832192Z","end":"2026-09-23T11:29:02.072952Z","steps":["trace[1948339561] 'process raft request' (duration: 240.607459ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:02.072991Z","caller":"traceutil/trace.go:171","msg":"trace[2046858178] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"473.662358ms","start":"2026-09-23T11:29:01.599317Z","end":"2026-09-23T11:29:02.072979Z","steps":["trace[2046858178] 'process raft request' (duration: 473.153684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.073054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.599283Z","time spent":"473.73894ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:02.073132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.454457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-23T11:29:02.073193Z","caller":"traceutil/trace.go:171","msg":"trace[1233770116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26432; }","duration":"247.55645ms","start":"2026-09-23T11:29:01.825619Z","end":"2026-09-23T11:29:02.073175Z","steps":["trace[1233770116] 'agreement among raft nodes before linearized reading' (duration: 247.376065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.073188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.661225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-23T11:29:02.073258Z","caller":"traceutil/trace.go:171","msg":"trace[149262271] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26432; }","duration":"390.773458ms","start":"2026-09-23T11:29:01.682461Z","end":"2026-09-23T11:29:02.073235Z","steps":["trace[149262271] 'agreement among raft nodes before linearized reading' (duration: 390.558372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.073315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.68242Z","time spent":"390.88145ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-23T11:29:02.378125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.185601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:29:02.378481Z","caller":"traceutil/trace.go:171","msg":"trace[1713412245] linearizableReadLoop","detail":"{readStateIndex:27311; appliedIndex:27309; }","duration":"305.43772ms","start":"2026-09-23T11:29:02.072999Z","end":"2026-09-23T11:29:02.378437Z","steps":["trace[1713412245] 'read index received' (duration: 201.796286ms)","trace[1713412245] 'applied index is now lower than readState.Index' (duration: 103.640364ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:02.378574Z","caller":"traceutil/trace.go:171","msg":"trace[70121573] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"358.67286ms","start":"2026-09-23T11:29:02.019882Z","end":"2026-09-23T11:29:02.378555Z","steps":["trace[70121573] 'process raft request' (duration: 358.454684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.378684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:02.019864Z","time spent":"358.758183ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:02.378706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.099275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:02.378789Z","caller":"traceutil/trace.go:171","msg":"trace[1725668453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26434; }","duration":"392.206637ms","start":"2026-09-23T11:29:01.98657Z","end":"2026-09-23T11:29:02.378777Z","steps":["trace[1725668453] 'agreement among raft nodes before linearized reading' (duration: 392.062813ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:02.378789Z","caller":"traceutil/trace.go:171","msg":"trace[1457295753] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"427.600555ms","start":"2026-09-23T11:29:01.951167Z","end":"2026-09-23T11:29:02.378767Z","steps":["trace[1457295753] 'process raft request' (duration: 323.660763ms)","trace[1457295753] 'compare' (duration: 102.852382ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:02.37887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.986556Z","time spent":"392.30281ms","remote":"127.0.0.1:41458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T11:29:02.378885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.951147Z","time spent":"427.695408ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:02.378903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.878811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-23T11:29:02.37901Z","caller":"traceutil/trace.go:171","msg":"trace[257706588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26434; }","duration":"361.013675ms","start":"2026-09-23T11:29:02.017971Z","end":"2026-09-23T11:29:02.378985Z","steps":["trace[257706588] 'agreement among raft nodes before linearized reading' (duration: 360.602733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.379022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.334576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-09-23T11:29:02.379066Z","caller":"traceutil/trace.go:171","msg":"trace[1195947540] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:26434; }","duration":"428.416938ms","start":"2026-09-23T11:29:01.950631Z","end":"2026-09-23T11:29:02.379048Z","steps":["trace[1195947540] 'agreement among raft nodes before linearized reading' (duration: 428.284464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.379066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:02.017937Z","time spent":"361.119598ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-23T11:29:02.379101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:01.950605Z","time spent":"428.48906ms","remote":"127.0.0.1:41638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2107,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-09-23T11:29:02.381027Z","caller":"traceutil/trace.go:171","msg":"trace[406898626] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"302.705214ms","start":"2026-09-23T11:29:02.078304Z","end":"2026-09-23T11:29:02.381009Z","steps":["trace[406898626] 'process raft request' (duration: 302.57519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.381106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:02.078274Z","time spent":"302.792206ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:02.381669Z","caller":"traceutil/trace.go:171","msg":"trace[1178344253] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"303.230739ms","start":"2026-09-23T11:29:02.078401Z","end":"2026-09-23T11:29:02.381632Z","steps":["trace[1178344253] 'process raft request' (duration: 302.566291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.381812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.584799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:29:02.381883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:02.078394Z","time spent":"303.360402ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:02.381897Z","caller":"traceutil/trace.go:171","msg":"trace[739745546] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:26436; }","duration":"113.770794ms","start":"2026-09-23T11:29:02.268109Z","end":"2026-09-23T11:29:02.38188Z","steps":["trace[739745546] 'agreement among raft nodes before linearized reading' (duration: 113.554928ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:02.560858Z","caller":"traceutil/trace.go:171","msg":"trace[211382233] linearizableReadLoop","detail":"{readStateIndex:27314; appliedIndex:27313; }","duration":"171.204721ms","start":"2026-09-23T11:29:02.389622Z","end":"2026-09-23T11:29:02.560827Z","steps":["trace[211382233] 'read index received' (duration: 153.723688ms)","trace[211382233] 'applied index is now lower than readState.Index' (duration: 17.479783ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:02.561019Z","caller":"traceutil/trace.go:171","msg":"trace[2045833425] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"174.030219ms","start":"2026-09-23T11:29:02.386973Z","end":"2026-09-23T11:29:02.561003Z","steps":["trace[2045833425] 'process raft request' (duration: 156.433933ms)","trace[2045833425] 'compare' (duration: 17.293538ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:02.561334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.690084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bd2ff5a4-364a-4fce-adb6-3985262019be\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-09-23T11:29:02.561377Z","caller":"traceutil/trace.go:171","msg":"trace[887778020] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bd2ff5a4-364a-4fce-adb6-3985262019be; range_end:; response_count:1; response_revision:26437; }","duration":"171.781617ms","start":"2026-09-23T11:29:02.389582Z","end":"2026-09-23T11:29:02.561364Z","steps":["trace[887778020] 'agreement among raft nodes before linearized reading' (duration: 171.591662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:02.561594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.802082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-23T11:29:02.561681Z","caller":"traceutil/trace.go:171","msg":"trace[320516490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26437; }","duration":"135.935816ms","start":"2026-09-23T11:29:02.425725Z","end":"2026-09-23T11:29:02.561661Z","steps":["trace[320516490] 'agreement among raft nodes before linearized reading' (duration: 135.594847ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:02.704606Z","caller":"traceutil/trace.go:171","msg":"trace[1252022220] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"137.994143ms","start":"2026-09-23T11:29:02.566571Z","end":"2026-09-23T11:29:02.704565Z","steps":["trace[1252022220] 'process raft request' (duration: 134.325931ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:03.407741Z","caller":"traceutil/trace.go:171","msg":"trace[1001177390] linearizableReadLoop","detail":"{readStateIndex:27316; appliedIndex:27315; }","duration":"180.520588ms","start":"2026-09-23T11:29:03.227182Z","end":"2026-09-23T11:29:03.407703Z","steps":["trace[1001177390] 'read index received' (duration: 180.361734ms)","trace[1001177390] 'applied index is now lower than readState.Index' (duration: 157.774µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:03.40788Z","caller":"traceutil/trace.go:171","msg":"trace[787087517] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"212.970095ms","start":"2026-09-23T11:29:03.194871Z","end":"2026-09-23T11:29:03.407841Z","steps":["trace[787087517] 'process raft request' (duration: 212.575004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:03.407989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.770964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:03.408046Z","caller":"traceutil/trace.go:171","msg":"trace[1484789243] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26439; }","duration":"180.914389ms","start":"2026-09-23T11:29:03.227114Z","end":"2026-09-23T11:29:03.408028Z","steps":["trace[1484789243] 'agreement among raft nodes before linearized reading' (duration: 180.721334ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:03.50091Z","caller":"traceutil/trace.go:171","msg":"trace[735841748] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"211.072582ms","start":"2026-09-23T11:29:03.289802Z","end":"2026-09-23T11:29:03.500875Z","steps":["trace[735841748] 'process raft request' (duration: 178.600774ms)","trace[735841748] 'compare' (duration: 32.19569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:03.970606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.967357ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-23T11:29:03.97076Z","caller":"traceutil/trace.go:171","msg":"trace[560511929] linearizableReadLoop","detail":"{readStateIndex:27319; appliedIndex:27318; }","duration":"118.256197ms","start":"2026-09-23T11:29:03.852471Z","end":"2026-09-23T11:29:03.970728Z","steps":["trace[560511929] 'read index received' (duration: 112.283µs)","trace[560511929] 'applied index is now lower than readState.Index' (duration: 118.141734ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:03.970895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.398932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:03.970938Z","caller":"traceutil/trace.go:171","msg":"trace[860143007] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:26441; }","duration":"118.510314ms","start":"2026-09-23T11:29:03.852412Z","end":"2026-09-23T11:29:03.970922Z","steps":["trace[860143007] 'agreement among raft nodes before linearized reading' (duration: 118.387921ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:04.068384Z","caller":"traceutil/trace.go:171","msg":"trace[242880090] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"117.948199ms","start":"2026-09-23T11:29:03.950378Z","end":"2026-09-23T11:29:04.068326Z","steps":["trace[242880090] 'process raft request' (duration: 117.657281ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:04.28231Z","caller":"traceutil/trace.go:171","msg":"trace[2007261516] linearizableReadLoop","detail":"{readStateIndex:27324; appliedIndex:27321; }","duration":"139.399902ms","start":"2026-09-23T11:29:04.14282Z","end":"2026-09-23T11:29:04.28222Z","steps":["trace[2007261516] 'read index received' (duration: 138.373564ms)","trace[2007261516] 'applied index is now lower than readState.Index' (duration: 1.025308ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:04.282559Z","caller":"traceutil/trace.go:171","msg":"trace[639265822] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"185.938907ms","start":"2026-09-23T11:29:04.096543Z","end":"2026-09-23T11:29:04.282482Z","steps":["trace[639265822] 'process raft request' (duration: 185.569487ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:04.28262Z","caller":"traceutil/trace.go:171","msg":"trace[1017855448] transaction","detail":"{read_only:false; response_revision:26445; number_of_response:1; }","duration":"201.752355ms","start":"2026-09-23T11:29:04.080788Z","end":"2026-09-23T11:29:04.282541Z","steps":["trace[1017855448] 'process raft request' (duration: 201.20977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:04.282795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.921436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19188"} {"level":"info","ts":"2026-09-23T11:29:04.282838Z","caller":"traceutil/trace.go:171","msg":"trace[1042693163] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26446; }","duration":"140.04529ms","start":"2026-09-23T11:29:04.14278Z","end":"2026-09-23T11:29:04.282825Z","steps":["trace[1042693163] 'agreement among raft nodes before linearized reading' (duration: 139.631748ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:04.283068Z","caller":"traceutil/trace.go:171","msg":"trace[1224581415] transaction","detail":"{read_only:false; response_revision:26444; number_of_response:1; }","duration":"202.402533ms","start":"2026-09-23T11:29:04.080642Z","end":"2026-09-23T11:29:04.283045Z","steps":["trace[1224581415] 'process raft request' (duration: 200.563072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:04.524596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.398026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:04.524742Z","caller":"traceutil/trace.go:171","msg":"trace[682914679] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26446; }","duration":"141.623353ms","start":"2026-09-23T11:29:04.383091Z","end":"2026-09-23T11:29:04.524714Z","steps":["trace[682914679] 'range keys from in-memory index tree' (duration: 141.17242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:04.524605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.374473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-23T11:29:04.524836Z","caller":"traceutil/trace.go:171","msg":"trace[21260635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26446; }","duration":"138.669792ms","start":"2026-09-23T11:29:04.38613Z","end":"2026-09-23T11:29:04.524799Z","steps":["trace[21260635] 'range keys from in-memory index tree' (duration: 138.098735ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:04.524918Z","caller":"traceutil/trace.go:171","msg":"trace[676283622] linearizableReadLoop","detail":"{readStateIndex:27325; appliedIndex:27324; }","duration":"137.990312ms","start":"2026-09-23T11:29:04.386904Z","end":"2026-09-23T11:29:04.524895Z","steps":["trace[676283622] 'read index received' (duration: 86.432408ms)","trace[676283622] 'applied index is now lower than readState.Index' (duration: 51.556134ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:04.524605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.920554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:29:04.526421Z","caller":"traceutil/trace.go:171","msg":"trace[1078392909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26446; }","duration":"142.863158ms","start":"2026-09-23T11:29:04.383493Z","end":"2026-09-23T11:29:04.526356Z","steps":["trace[1078392909] 'range keys from in-memory index tree' (duration: 140.582304ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:04.525145Z","caller":"traceutil/trace.go:171","msg":"trace[2075740196] transaction","detail":"{read_only:false; response_revision:26447; number_of_response:1; }","duration":"138.868157ms","start":"2026-09-23T11:29:04.386261Z","end":"2026-09-23T11:29:04.52513Z","steps":["trace[2075740196] 'process raft request' (duration: 87.128577ms)","trace[2075740196] 'compare' (duration: 51.326808ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:04.525444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.518947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-23T11:29:04.527122Z","caller":"traceutil/trace.go:171","msg":"trace[597569702] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26447; }","duration":"139.751091ms","start":"2026-09-23T11:29:04.386869Z","end":"2026-09-23T11:29:04.52662Z","steps":["trace[597569702] 'agreement among raft nodes before linearized reading' (duration: 138.398804ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:16.566396Z","caller":"traceutil/trace.go:171","msg":"trace[228073457] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"449.475559ms","start":"2026-09-23T11:29:16.116892Z","end":"2026-09-23T11:29:16.566368Z","steps":["trace[228073457] 'process raft request' (duration: 449.306084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:16.566586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:16.116859Z","time spent":"449.614383ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:16.580813Z","caller":"traceutil/trace.go:171","msg":"trace[735994365] linearizableReadLoop","detail":"{readStateIndex:27418; appliedIndex:27414; }","duration":"231.153137ms","start":"2026-09-23T11:29:16.349646Z","end":"2026-09-23T11:29:16.580799Z","steps":["trace[735994365] 'read index received' (duration: 217.442178ms)","trace[735994365] 'applied index is now lower than readState.Index' (duration: 13.710419ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:16.580967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.303911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:16.580991Z","caller":"traceutil/trace.go:171","msg":"trace[271404941] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26538; }","duration":"231.374103ms","start":"2026-09-23T11:29:16.349607Z","end":"2026-09-23T11:29:16.580982Z","steps":["trace[271404941] 'agreement among raft nodes before linearized reading' (duration: 231.251319ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:16.581216Z","caller":"traceutil/trace.go:171","msg":"trace[621211556] transaction","detail":"{read_only:false; response_revision:26535; number_of_response:1; }","duration":"398.647575ms","start":"2026-09-23T11:29:16.182563Z","end":"2026-09-23T11:29:16.58121Z","steps":["trace[621211556] 'process raft request' (duration: 398.024458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:16.581287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:16.182508Z","time spent":"398.733027ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:16.581365Z","caller":"traceutil/trace.go:171","msg":"trace[1621019064] transaction","detail":"{read_only:false; response_revision:26536; number_of_response:1; }","duration":"236.267008ms","start":"2026-09-23T11:29:16.345092Z","end":"2026-09-23T11:29:16.581359Z","steps":["trace[1621019064] 'process raft request' (duration: 235.59509ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:16.581426Z","caller":"traceutil/trace.go:171","msg":"trace[590683211] transaction","detail":"{read_only:false; response_revision:26537; number_of_response:1; }","duration":"234.14369ms","start":"2026-09-23T11:29:16.347277Z","end":"2026-09-23T11:29:16.581421Z","steps":["trace[590683211] 'process raft request' (duration: 233.446911ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:16.581483Z","caller":"traceutil/trace.go:171","msg":"trace[1199836883] transaction","detail":"{read_only:false; response_revision:26538; number_of_response:1; }","duration":"231.941509ms","start":"2026-09-23T11:29:16.349537Z","end":"2026-09-23T11:29:16.581478Z","steps":["trace[1199836883] 'process raft request' (duration: 231.235379ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:16.918598Z","caller":"traceutil/trace.go:171","msg":"trace[1225652811] linearizableReadLoop","detail":"{readStateIndex:27421; appliedIndex:27420; }","duration":"246.83789ms","start":"2026-09-23T11:29:16.67172Z","end":"2026-09-23T11:29:16.918558Z","steps":["trace[1225652811] 'read index received' (duration: 244.346122ms)","trace[1225652811] 'applied index is now lower than readState.Index' (duration: 2.489998ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:16.918708Z","caller":"traceutil/trace.go:171","msg":"trace[567850169] transaction","detail":"{read_only:false; response_revision:26541; number_of_response:1; }","duration":"253.901756ms","start":"2026-09-23T11:29:16.664769Z","end":"2026-09-23T11:29:16.918671Z","steps":["trace[567850169] 'process raft request' (duration: 251.362396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:16.91885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.071347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:29:16.918924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.091873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:29:16.918963Z","caller":"traceutil/trace.go:171","msg":"trace[1231589840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26541; }","duration":"245.152704ms","start":"2026-09-23T11:29:16.673795Z","end":"2026-09-23T11:29:16.918948Z","steps":["trace[1231589840] 'agreement among raft nodes before linearized reading' (duration: 244.956509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:16.919244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.171671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-23T11:29:16.919291Z","caller":"traceutil/trace.go:171","msg":"trace[248314631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26541; }","duration":"141.253523ms","start":"2026-09-23T11:29:16.778022Z","end":"2026-09-23T11:29:16.919276Z","steps":["trace[248314631] 'agreement among raft nodes before linearized reading' (duration: 141.071458ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:16.918988Z","caller":"traceutil/trace.go:171","msg":"trace[1244955940] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:26541; }","duration":"247.263382ms","start":"2026-09-23T11:29:16.671682Z","end":"2026-09-23T11:29:16.918946Z","steps":["trace[1244955940] 'agreement among raft nodes before linearized reading' (duration: 246.989835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:16.919491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.373628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-23T11:29:16.919651Z","caller":"traceutil/trace.go:171","msg":"trace[644767396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26541; }","duration":"233.571794ms","start":"2026-09-23T11:29:16.686044Z","end":"2026-09-23T11:29:16.919616Z","steps":["trace[644767396] 'agreement among raft nodes before linearized reading' (duration: 233.113481ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:17.360939Z","caller":"traceutil/trace.go:171","msg":"trace[80530327] transaction","detail":"{read_only:false; response_revision:26543; number_of_response:1; }","duration":"436.438469ms","start":"2026-09-23T11:29:16.924466Z","end":"2026-09-23T11:29:17.360905Z","steps":["trace[80530327] 'process raft request' (duration: 436.047718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.361166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:16.924433Z","time spent":"436.619374ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:17.361293Z","caller":"traceutil/trace.go:171","msg":"trace[1454440593] transaction","detail":"{read_only:false; response_revision:26544; number_of_response:1; }","duration":"436.520162ms","start":"2026-09-23T11:29:16.924703Z","end":"2026-09-23T11:29:17.361223Z","steps":["trace[1454440593] 'process raft request' (duration: 436.134661ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:17.361333Z","caller":"traceutil/trace.go:171","msg":"trace[1882336637] linearizableReadLoop","detail":"{readStateIndex:27425; appliedIndex:27421; }","duration":"374.641401ms","start":"2026-09-23T11:29:16.98665Z","end":"2026-09-23T11:29:17.361292Z","steps":["trace[1882336637] 'read index received' (duration: 1.666536ms)","trace[1882336637] 'applied index is now lower than readState.Index' (duration: 372.973335ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:17.361482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.814727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:29:17.361581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:16.92464Z","time spent":"436.782959ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:17.361596Z","caller":"traceutil/trace.go:171","msg":"trace[139702397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26545; }","duration":"374.991861ms","start":"2026-09-23T11:29:16.986583Z","end":"2026-09-23T11:29:17.361574Z","steps":["trace[139702397] 'agreement among raft nodes before linearized reading' (duration: 374.820456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.36164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:16.9865Z","time spent":"375.130235ms","remote":"127.0.0.1:41458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T11:29:17.361417Z","caller":"traceutil/trace.go:171","msg":"trace[488165568] transaction","detail":"{read_only:false; response_revision:26545; number_of_response:1; }","duration":"436.45626ms","start":"2026-09-23T11:29:16.924929Z","end":"2026-09-23T11:29:17.361386Z","steps":["trace[488165568] 'process raft request' (duration: 436.169242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.361714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.046529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"warn","ts":"2026-09-23T11:29:17.361735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.472473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"info","ts":"2026-09-23T11:29:17.361766Z","caller":"traceutil/trace.go:171","msg":"trace[803910104] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26545; }","duration":"364.130801ms","start":"2026-09-23T11:29:16.997615Z","end":"2026-09-23T11:29:17.361746Z","steps":["trace[803910104] 'agreement among raft nodes before linearized reading' (duration: 363.961796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.3618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:16.997598Z","time spent":"364.194813ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1602,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-09-23T11:29:17.361807Z","caller":"traceutil/trace.go:171","msg":"trace[1255827644] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26545; }","duration":"367.613637ms","start":"2026-09-23T11:29:16.99417Z","end":"2026-09-23T11:29:17.361784Z","steps":["trace[1255827644] 'agreement among raft nodes before linearized reading' (duration: 367.338629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.361856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:16.994145Z","time spent":"367.69626ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1602,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-23T11:29:17.36205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:16.924921Z","time spent":"436.861851ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:17.724817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.52642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:29:17.724936Z","caller":"traceutil/trace.go:171","msg":"trace[434863638] linearizableReadLoop","detail":"{readStateIndex:27426; appliedIndex:27425; }","duration":"252.571129ms","start":"2026-09-23T11:29:17.472346Z","end":"2026-09-23T11:29:17.724917Z","steps":["trace[434863638] 'read index received' (duration: 73.012µs)","trace[434863638] 'applied index is now lower than readState.Index' (duration: 252.496077ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:17.725121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.787745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:29:17.725156Z","caller":"traceutil/trace.go:171","msg":"trace[1451828913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26546; }","duration":"252.876527ms","start":"2026-09-23T11:29:17.472269Z","end":"2026-09-23T11:29:17.725146Z","steps":["trace[1451828913] 'agreement among raft nodes before linearized reading' (duration: 252.698223ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:17.72514Z","caller":"traceutil/trace.go:171","msg":"trace[199296252] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"353.211279ms","start":"2026-09-23T11:29:17.371897Z","end":"2026-09-23T11:29:17.725109Z","steps":["trace[199296252] 'process raft request' (duration: 21.282128ms)","trace[199296252] 'compare' (duration: 331.331105ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:17.725476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.371875Z","time spent":"353.412935ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:18.304923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14705555489194493225,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-23T11:29:18.389326Z","caller":"traceutil/trace.go:171","msg":"trace[348906940] transaction","detail":"{read_only:false; response_revision:26547; number_of_response:1; }","duration":"819.162254ms","start":"2026-09-23T11:29:17.570126Z","end":"2026-09-23T11:29:18.389288Z","steps":["trace[348906940] 'process raft request' (duration: 742.871316ms)","trace[348906940] 'compare' (duration: 76.027841ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:18.389613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.570087Z","time spent":"819.37866ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:18.3897Z","caller":"traceutil/trace.go:171","msg":"trace[816751006] linearizableReadLoop","detail":"{readStateIndex:27429; appliedIndex:27426; }","duration":"585.861068ms","start":"2026-09-23T11:29:17.80381Z","end":"2026-09-23T11:29:18.389671Z","steps":["trace[816751006] 'read index received' (duration: 509.22168ms)","trace[816751006] 'applied index is now lower than readState.Index' (duration: 76.638418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:18.390246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.420243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:18.390328Z","caller":"traceutil/trace.go:171","msg":"trace[562179428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:26549; }","duration":"586.502895ms","start":"2026-09-23T11:29:17.803802Z","end":"2026-09-23T11:29:18.390305Z","steps":["trace[562179428] 'agreement among raft nodes before linearized reading' (duration: 586.350001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:18.390379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.803741Z","time spent":"586.622759ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:29:18.390382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.014855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:18.390504Z","caller":"traceutil/trace.go:171","msg":"trace[574293671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26549; }","duration":"403.206001ms","start":"2026-09-23T11:29:17.987243Z","end":"2026-09-23T11:29:18.390449Z","steps":["trace[574293671] 'agreement among raft nodes before linearized reading' (duration: 403.000496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:18.390573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.987215Z","time spent":"403.347784ms","remote":"127.0.0.1:41458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T11:29:18.390603Z","caller":"traceutil/trace.go:171","msg":"trace[668647970] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"653.777245ms","start":"2026-09-23T11:29:17.736801Z","end":"2026-09-23T11:29:18.390578Z","steps":["trace[668647970] 'process raft request' (duration: 652.698905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:18.390703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.736773Z","time spent":"653.876677ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2720,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:18.390846Z","caller":"traceutil/trace.go:171","msg":"trace[509600228] transaction","detail":"{read_only:false; response_revision:26548; number_of_response:1; }","duration":"659.574604ms","start":"2026-09-23T11:29:17.730264Z","end":"2026-09-23T11:29:18.389839Z","steps":["trace[509600228] 'process raft request' (duration: 658.955927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:18.390974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.730224Z","time spent":"660.657335ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:18.877664Z","caller":"traceutil/trace.go:171","msg":"trace[1683574081] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"452.631916ms","start":"2026-09-23T11:29:18.424992Z","end":"2026-09-23T11:29:18.877624Z","steps":["trace[1683574081] 'process raft request' (duration: 352.826819ms)","trace[1683574081] 'compare' (duration: 99.575051ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:18.877874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.424957Z","time spent":"452.799381ms","remote":"127.0.0.1:41504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:19.043189Z","caller":"traceutil/trace.go:171","msg":"trace[903612030] linearizableReadLoop","detail":"{readStateIndex:27438; appliedIndex:27430; }","duration":"404.731063ms","start":"2026-09-23T11:29:18.638432Z","end":"2026-09-23T11:29:19.043163Z","steps":["trace[903612030] 'read index received' (duration: 139.659249ms)","trace[903612030] 'applied index is now lower than readState.Index' (duration: 265.070654ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:19.043348Z","caller":"traceutil/trace.go:171","msg":"trace[2101589255] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"455.740012ms","start":"2026-09-23T11:29:18.587594Z","end":"2026-09-23T11:29:19.043334Z","steps":["trace[2101589255] 'process raft request' (duration: 455.383542ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:19.043317Z","caller":"traceutil/trace.go:171","msg":"trace[1716128410] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"456.120873ms","start":"2026-09-23T11:29:18.58715Z","end":"2026-09-23T11:29:19.043271Z","steps":["trace[1716128410] 'process raft request' (duration: 455.701962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:19.04348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.587566Z","time spent":"455.808884ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:19.043564Z","caller":"traceutil/trace.go:171","msg":"trace[697767289] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"453.82329ms","start":"2026-09-23T11:29:18.589671Z","end":"2026-09-23T11:29:19.043494Z","steps":["trace[697767289] 'process raft request' (duration: 453.411508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:19.043655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.587114Z","time spent":"456.290618ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:19.043828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.054676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-23T11:29:19.043868Z","caller":"traceutil/trace.go:171","msg":"trace[1762894098] transaction","detail":"{read_only:false; response_revision:26551; number_of_response:1; }","duration":"615.60696ms","start":"2026-09-23T11:29:18.428248Z","end":"2026-09-23T11:29:19.043855Z","steps":["trace[1762894098] 'process raft request' (duration: 614.372015ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:19.043888Z","caller":"traceutil/trace.go:171","msg":"trace[1307775943] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26557; }","duration":"163.250011ms","start":"2026-09-23T11:29:18.880623Z","end":"2026-09-23T11:29:19.043873Z","steps":["trace[1307775943] 'agreement among raft nodes before linearized reading' (duration: 162.984324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:19.043925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.428216Z","time spent":"615.680152ms","remote":"127.0.0.1:41674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1240,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-23T11:29:19.044075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.589633Z","time spent":"454.299133ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:19.043503Z","caller":"traceutil/trace.go:171","msg":"trace[1084628377] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"430.399653ms","start":"2026-09-23T11:29:18.613059Z","end":"2026-09-23T11:29:19.043458Z","steps":["trace[1084628377] 'process raft request' (duration: 430.059363ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:19.043564Z","caller":"traceutil/trace.go:171","msg":"trace[744949821] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"454.681913ms","start":"2026-09-23T11:29:18.588795Z","end":"2026-09-23T11:29:19.043477Z","steps":["trace[744949821] 'process raft request' (duration: 454.21822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:19.04422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.792482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-23T11:29:19.044284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.613027Z","time spent":"431.220835ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:19.044322Z","caller":"traceutil/trace.go:171","msg":"trace[1338528112] transaction","detail":"{read_only:false; response_revision:26552; number_of_response:1; }","duration":"471.19994ms","start":"2026-09-23T11:29:18.573105Z","end":"2026-09-23T11:29:19.044305Z","steps":["trace[1338528112] 'process raft request' (duration: 469.673658ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:19.044353Z","caller":"traceutil/trace.go:171","msg":"trace[1467432763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26557; }","duration":"405.938766ms","start":"2026-09-23T11:29:18.638373Z","end":"2026-09-23T11:29:19.044312Z","steps":["trace[1467432763] 'agreement among raft nodes before linearized reading' (duration: 405.7179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:19.044421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.573052Z","time spent":"471.318723ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:19.044272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.146178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-09-23T11:29:19.044448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.638355Z","time spent":"406.079931ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-23T11:29:19.044509Z","caller":"traceutil/trace.go:171","msg":"trace[2035947004] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26557; }","duration":"121.428565ms","start":"2026-09-23T11:29:18.923066Z","end":"2026-09-23T11:29:19.044494Z","steps":["trace[2035947004] 'agreement among raft nodes before linearized reading' (duration: 121.134797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:19.045999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:18.588757Z","time spent":"455.513116ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:26.216183Z","caller":"traceutil/trace.go:171","msg":"trace[1186620578] linearizableReadLoop","detail":"{readStateIndex:27564; appliedIndex:27563; }","duration":"137.708755ms","start":"2026-09-23T11:29:26.078445Z","end":"2026-09-23T11:29:26.216154Z","steps":["trace[1186620578] 'read index received' (duration: 137.424297ms)","trace[1186620578] 'applied index is now lower than readState.Index' (duration: 283.528µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:26.216344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.870949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:26.216376Z","caller":"traceutil/trace.go:171","msg":"trace[629299575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26680; }","duration":"137.978892ms","start":"2026-09-23T11:29:26.078387Z","end":"2026-09-23T11:29:26.216366Z","steps":["trace[629299575] 'agreement among raft nodes before linearized reading' (duration: 137.88721ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:26.216349Z","caller":"traceutil/trace.go:171","msg":"trace[1651986935] transaction","detail":"{read_only:false; response_revision:26680; number_of_response:1; }","duration":"148.835362ms","start":"2026-09-23T11:29:26.067467Z","end":"2026-09-23T11:29:26.216303Z","steps":["trace[1651986935] 'process raft request' (duration: 148.484573ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:26.468896Z","caller":"traceutil/trace.go:171","msg":"trace[59933726] transaction","detail":"{read_only:false; response_revision:26681; number_of_response:1; }","duration":"245.260127ms","start":"2026-09-23T11:29:26.2236Z","end":"2026-09-23T11:29:26.46886Z","steps":["trace[59933726] 'process raft request' (duration: 158.089708ms)","trace[59933726] 'compare' (duration: 86.911292ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:26.546478Z","caller":"traceutil/trace.go:171","msg":"trace[173103408] transaction","detail":"{read_only:false; response_revision:26682; number_of_response:1; }","duration":"320.440195ms","start":"2026-09-23T11:29:26.226008Z","end":"2026-09-23T11:29:26.546449Z","steps":["trace[173103408] 'process raft request' (duration: 320.190828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:26.546681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:26.225964Z","time spent":"320.622009ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":940,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:26.546889Z","caller":"traceutil/trace.go:171","msg":"trace[1223882462] transaction","detail":"{read_only:false; response_revision:26684; number_of_response:1; }","duration":"320.729772ms","start":"2026-09-23T11:29:26.226124Z","end":"2026-09-23T11:29:26.546854Z","steps":["trace[1223882462] 'process raft request' (duration: 320.595238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:26.547087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:26.226097Z","time spent":"320.882566ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:26.54712Z","caller":"traceutil/trace.go:171","msg":"trace[48279123] transaction","detail":"{read_only:false; response_revision:26683; number_of_response:1; }","duration":"321.01309ms","start":"2026-09-23T11:29:26.226088Z","end":"2026-09-23T11:29:26.547101Z","steps":["trace[48279123] 'process raft request' (duration: 320.28279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:26.547217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:26.226072Z","time spent":"321.108383ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:26.548946Z","caller":"traceutil/trace.go:171","msg":"trace[850629363] linearizableReadLoop","detail":"{readStateIndex:27569; appliedIndex:27566; }","duration":"108.629892ms","start":"2026-09-23T11:29:26.440295Z","end":"2026-09-23T11:29:26.548925Z","steps":["trace[850629363] 'read index received' (duration: 106.344419ms)","trace[850629363] 'applied index is now lower than readState.Index' (duration: 2.284333ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:26.549062Z","caller":"traceutil/trace.go:171","msg":"trace[975724362] transaction","detail":"{read_only:false; response_revision:26685; number_of_response:1; }","duration":"130.198238ms","start":"2026-09-23T11:29:26.41884Z","end":"2026-09-23T11:29:26.549039Z","steps":["trace[975724362] 'process raft request' (duration: 129.758836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:26.549251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.95029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:29:26.549317Z","caller":"traceutil/trace.go:171","msg":"trace[1368300248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26685; }","duration":"109.073484ms","start":"2026-09-23T11:29:26.440226Z","end":"2026-09-23T11:29:26.5493Z","steps":["trace[1368300248] 'agreement among raft nodes before linearized reading' (duration: 108.784216ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.363804Z","caller":"traceutil/trace.go:171","msg":"trace[1109753220] transaction","detail":"{read_only:false; response_revision:26871; number_of_response:1; }","duration":"182.183671ms","start":"2026-09-23T11:29:49.181585Z","end":"2026-09-23T11:29:49.363768Z","steps":["trace[1109753220] 'process raft request' (duration: 181.930314ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.364146Z","caller":"traceutil/trace.go:171","msg":"trace[673066000] transaction","detail":"{read_only:false; response_revision:26872; number_of_response:1; }","duration":"181.305266ms","start":"2026-09-23T11:29:49.182799Z","end":"2026-09-23T11:29:49.364104Z","steps":["trace[673066000] 'process raft request' (duration: 180.917415ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.364166Z","caller":"traceutil/trace.go:171","msg":"trace[973834206] linearizableReadLoop","detail":"{readStateIndex:27764; appliedIndex:27759; }","duration":"128.331384ms","start":"2026-09-23T11:29:49.235814Z","end":"2026-09-23T11:29:49.364146Z","steps":["trace[973834206] 'read index received' (duration: 127.659135ms)","trace[973834206] 'applied index is now lower than readState.Index' (duration: 671.339µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:49.364376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.543339ms","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-09-23T11:29:49.364445Z","caller":"traceutil/trace.go:171","msg":"trace[2059740587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26875; }","duration":"128.672953ms","start":"2026-09-23T11:29:49.235757Z","end":"2026-09-23T11:29:49.36443Z","steps":["trace[2059740587] 'agreement among raft nodes before linearized reading' (duration: 128.451017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:49.364597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.543002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-23T11:29:49.364614Z","caller":"traceutil/trace.go:171","msg":"trace[338056587] transaction","detail":"{read_only:false; response_revision:26873; number_of_response:1; }","duration":"174.363125ms","start":"2026-09-23T11:29:49.190239Z","end":"2026-09-23T11:29:49.364602Z","steps":["trace[338056587] 'process raft request' (duration: 173.734197ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.364619Z","caller":"traceutil/trace.go:171","msg":"trace[211585345] transaction","detail":"{read_only:false; response_revision:26874; number_of_response:1; }","duration":"173.79953ms","start":"2026-09-23T11:29:49.190802Z","end":"2026-09-23T11:29:49.364602Z","steps":["trace[211585345] 'process raft request' (duration: 173.256395ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.364628Z","caller":"traceutil/trace.go:171","msg":"trace[1018104476] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26875; }","duration":"127.628984ms","start":"2026-09-23T11:29:49.23699Z","end":"2026-09-23T11:29:49.364619Z","steps":["trace[1018104476] 'agreement among raft nodes before linearized reading' (duration: 127.431299ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.364838Z","caller":"traceutil/trace.go:171","msg":"trace[535160880] transaction","detail":"{read_only:false; response_revision:26875; number_of_response:1; }","duration":"170.750546ms","start":"2026-09-23T11:29:49.19403Z","end":"2026-09-23T11:29:49.36478Z","steps":["trace[535160880] 'process raft request' (duration: 170.073217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:49.653728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.870161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-09-23T11:29:49.653777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.403943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:49.653872Z","caller":"traceutil/trace.go:171","msg":"trace[535576535] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26877; }","duration":"125.569628ms","start":"2026-09-23T11:29:49.528277Z","end":"2026-09-23T11:29:49.653847Z","steps":["trace[535576535] 'count revisions from in-memory index tree' (duration: 125.28824ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.653809Z","caller":"traceutil/trace.go:171","msg":"trace[476094177] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26877; }","duration":"190.959283ms","start":"2026-09-23T11:29:49.462831Z","end":"2026-09-23T11:29:49.65379Z","steps":["trace[476094177] 'range keys from in-memory index tree' (duration: 190.654095ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.993127Z","caller":"traceutil/trace.go:171","msg":"trace[2111516875] transaction","detail":"{read_only:false; response_revision:26879; number_of_response:1; }","duration":"330.250499ms","start":"2026-09-23T11:29:49.662849Z","end":"2026-09-23T11:29:49.993099Z","steps":["trace[2111516875] 'process raft request' (duration: 329.956221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:49.993332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:49.662796Z","time spent":"330.418333ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:49.993483Z","caller":"traceutil/trace.go:171","msg":"trace[992683042] transaction","detail":"{read_only:false; response_revision:26880; number_of_response:1; }","duration":"330.561968ms","start":"2026-09-23T11:29:49.662887Z","end":"2026-09-23T11:29:49.993449Z","steps":["trace[992683042] 'process raft request' (duration: 330.160367ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:49.993607Z","caller":"traceutil/trace.go:171","msg":"trace[1467991554] linearizableReadLoop","detail":"{readStateIndex:27769; appliedIndex:27766; }","duration":"330.230928ms","start":"2026-09-23T11:29:49.663341Z","end":"2026-09-23T11:29:49.993572Z","steps":["trace[1467991554] 'read index received' (duration: 75.465024ms)","trace[1467991554] 'applied index is now lower than readState.Index' (duration: 254.764534ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:49.993659Z","caller":"traceutil/trace.go:171","msg":"trace[925748869] transaction","detail":"{read_only:false; response_revision:26881; number_of_response:1; }","duration":"299.081999ms","start":"2026-09-23T11:29:49.694555Z","end":"2026-09-23T11:29:49.993637Z","steps":["trace[925748869] 'process raft request' (duration: 298.796601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:49.993696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:49.662849Z","time spent":"330.750513ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:49.993837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.753224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:49.993942Z","caller":"traceutil/trace.go:171","msg":"trace[714328451] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26881; }","duration":"229.94688ms","start":"2026-09-23T11:29:49.763972Z","end":"2026-09-23T11:29:49.993919Z","steps":["trace[714328451] 'agreement among raft nodes before linearized reading' (duration: 229.762585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:49.99386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.520887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-09-23T11:29:49.99411Z","caller":"traceutil/trace.go:171","msg":"trace[1171572538] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26881; }","duration":"330.818724ms","start":"2026-09-23T11:29:49.663266Z","end":"2026-09-23T11:29:49.994085Z","steps":["trace[1171572538] 'agreement among raft nodes before linearized reading' (duration: 330.435044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:49.994183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:49.663236Z","time spent":"330.928637ms","remote":"127.0.0.1:41674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":1470,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-09-23T11:29:50.945564Z","caller":"traceutil/trace.go:171","msg":"trace[58701678] linearizableReadLoop","detail":"{readStateIndex:27773; appliedIndex:27772; }","duration":"122.220025ms","start":"2026-09-23T11:29:50.82329Z","end":"2026-09-23T11:29:50.94551Z","steps":["trace[58701678] 'read index received' (duration: 122.004299ms)","trace[58701678] 'applied index is now lower than readState.Index' (duration: 214.606µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:50.945706Z","caller":"traceutil/trace.go:171","msg":"trace[1702216321] transaction","detail":"{read_only:false; response_revision:26884; number_of_response:1; }","duration":"221.169047ms","start":"2026-09-23T11:29:50.724489Z","end":"2026-09-23T11:29:50.945658Z","steps":["trace[1702216321] 'process raft request' (duration: 220.871579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:50.945808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.476992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:50.945911Z","caller":"traceutil/trace.go:171","msg":"trace[976669029] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26884; }","duration":"122.696928ms","start":"2026-09-23T11:29:50.823193Z","end":"2026-09-23T11:29:50.94589Z","steps":["trace[976669029] 'agreement among raft nodes before linearized reading' (duration: 122.459221ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:51.234034Z","caller":"traceutil/trace.go:171","msg":"trace[909809920] linearizableReadLoop","detail":"{readStateIndex:27774; appliedIndex:27773; }","duration":"155.907875ms","start":"2026-09-23T11:29:51.078086Z","end":"2026-09-23T11:29:51.233994Z","steps":["trace[909809920] 'read index received' (duration: 141.278871ms)","trace[909809920] 'applied index is now lower than readState.Index' (duration: 14.627124ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:51.234099Z","caller":"traceutil/trace.go:171","msg":"trace[1041387200] transaction","detail":"{read_only:false; response_revision:26885; number_of_response:1; }","duration":"164.934314ms","start":"2026-09-23T11:29:51.069077Z","end":"2026-09-23T11:29:51.234011Z","steps":["trace[1041387200] 'process raft request' (duration: 150.379202ms)","trace[1041387200] 'compare' (duration: 14.125971ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:51.234327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.211634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:51.234454Z","caller":"traceutil/trace.go:171","msg":"trace[1224407109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:26885; }","duration":"156.398329ms","start":"2026-09-23T11:29:51.078029Z","end":"2026-09-23T11:29:51.234428Z","steps":["trace[1224407109] 'agreement among raft nodes before linearized reading' (duration: 156.07997ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:53.42193Z","caller":"traceutil/trace.go:171","msg":"trace[1963786913] transaction","detail":"{read_only:false; response_revision:26901; number_of_response:1; }","duration":"111.040926ms","start":"2026-09-23T11:29:53.310853Z","end":"2026-09-23T11:29:53.421894Z","steps":["trace[1963786913] 'process raft request' (duration: 110.852941ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:53.482653Z","caller":"traceutil/trace.go:171","msg":"trace[1835957805] transaction","detail":"{read_only:false; response_revision:26902; number_of_response:1; }","duration":"103.20617ms","start":"2026-09-23T11:29:53.379422Z","end":"2026-09-23T11:29:53.482628Z","steps":["trace[1835957805] 'process raft request' (duration: 102.657565ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:53.600421Z","caller":"traceutil/trace.go:171","msg":"trace[237473531] transaction","detail":"{read_only:false; response_revision:26907; number_of_response:1; }","duration":"109.144544ms","start":"2026-09-23T11:29:53.491215Z","end":"2026-09-23T11:29:53.600359Z","steps":["trace[237473531] 'process raft request' (duration: 108.855966ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:53.660931Z","caller":"traceutil/trace.go:171","msg":"trace[561570916] transaction","detail":"{read_only:false; response_revision:26908; number_of_response:1; }","duration":"169.50671ms","start":"2026-09-23T11:29:53.491377Z","end":"2026-09-23T11:29:53.660884Z","steps":["trace[561570916] 'process raft request' (duration: 169.219403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:53.889424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.177036ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-23T11:29:53.889611Z","caller":"traceutil/trace.go:171","msg":"trace[353327746] linearizableReadLoop","detail":"{readStateIndex:27798; appliedIndex:27797; }","duration":"132.114988ms","start":"2026-09-23T11:29:53.757472Z","end":"2026-09-23T11:29:53.889587Z","steps":["trace[353327746] 'read index received' (duration: 27.301µs)","trace[353327746] 'applied index is now lower than readState.Index' (duration: 132.085887ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:53.88981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.321014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-23T11:29:53.889841Z","caller":"traceutil/trace.go:171","msg":"trace[1092559467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26908; }","duration":"132.404206ms","start":"2026-09-23T11:29:53.757425Z","end":"2026-09-23T11:29:53.889829Z","steps":["trace[1092559467] 'agreement among raft nodes before linearized reading' (duration: 132.223711ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:55.612746Z","caller":"traceutil/trace.go:171","msg":"trace[1828063995] transaction","detail":"{read_only:false; response_revision:26917; number_of_response:1; }","duration":"123.242123ms","start":"2026-09-23T11:29:55.489478Z","end":"2026-09-23T11:29:55.61272Z","steps":["trace[1828063995] 'process raft request' (duration: 123.067598ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:55.661388Z","caller":"traceutil/trace.go:171","msg":"trace[280641513] transaction","detail":"{read_only:false; response_revision:26919; number_of_response:1; }","duration":"168.490902ms","start":"2026-09-23T11:29:55.492871Z","end":"2026-09-23T11:29:55.661362Z","steps":["trace[280641513] 'process raft request' (duration: 168.428411ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:55.661444Z","caller":"traceutil/trace.go:171","msg":"trace[1269362492] transaction","detail":"{read_only:false; response_revision:26918; number_of_response:1; }","duration":"168.536944ms","start":"2026-09-23T11:29:55.492853Z","end":"2026-09-23T11:29:55.66139Z","steps":["trace[1269362492] 'process raft request' (duration: 168.297848ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:55.661741Z","caller":"traceutil/trace.go:171","msg":"trace[1799088486] transaction","detail":"{read_only:false; response_revision:26920; number_of_response:1; }","duration":"168.005619ms","start":"2026-09-23T11:29:55.493682Z","end":"2026-09-23T11:29:55.661688Z","steps":["trace[1799088486] 'process raft request' (duration: 167.64921ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:55.661865Z","caller":"traceutil/trace.go:171","msg":"trace[695354276] linearizableReadLoop","detail":"{readStateIndex:27811; appliedIndex:27806; }","duration":"147.268577ms","start":"2026-09-23T11:29:55.51457Z","end":"2026-09-23T11:29:55.661839Z","steps":["trace[695354276] 'read index received' (duration: 97.984056ms)","trace[695354276] 'applied index is now lower than readState.Index' (duration: 49.28352ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:55.66193Z","caller":"traceutil/trace.go:171","msg":"trace[2126090629] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"166.60447ms","start":"2026-09-23T11:29:55.495302Z","end":"2026-09-23T11:29:55.661906Z","steps":["trace[2126090629] 'process raft request' (duration: 166.297512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:55.662119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.490572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:55.66222Z","caller":"traceutil/trace.go:171","msg":"trace[458747541] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26921; }","duration":"147.632546ms","start":"2026-09-23T11:29:55.514561Z","end":"2026-09-23T11:29:55.662194Z","steps":["trace[458747541] 'agreement among raft nodes before linearized reading' (duration: 147.41731ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:03.60109Z","caller":"traceutil/trace.go:171","msg":"trace[1573948962] linearizableReadLoop","detail":"{readStateIndex:27879; appliedIndex:27878; }","duration":"121.445344ms","start":"2026-09-23T11:30:03.479615Z","end":"2026-09-23T11:30:03.60106Z","steps":["trace[1573948962] 'read index received' (duration: 121.189027ms)","trace[1573948962] 'applied index is now lower than readState.Index' (duration: 255.087µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:03.601153Z","caller":"traceutil/trace.go:171","msg":"trace[211105700] transaction","detail":"{read_only:false; response_revision:26988; number_of_response:1; }","duration":"134.121583ms","start":"2026-09-23T11:30:03.466991Z","end":"2026-09-23T11:30:03.601112Z","steps":["trace[211105700] 'process raft request' (duration: 133.859076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:03.601248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.609408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:03.601291Z","caller":"traceutil/trace.go:171","msg":"trace[1078110842] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:26988; }","duration":"121.751852ms","start":"2026-09-23T11:30:03.479527Z","end":"2026-09-23T11:30:03.601279Z","steps":["trace[1078110842] 'agreement among raft nodes before linearized reading' (duration: 121.645899ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:03.841931Z","caller":"traceutil/trace.go:171","msg":"trace[232351169] transaction","detail":"{read_only:false; response_revision:26989; number_of_response:1; }","duration":"200.401333ms","start":"2026-09-23T11:30:03.641482Z","end":"2026-09-23T11:30:03.841883Z","steps":["trace[232351169] 'process raft request' (duration: 187.950689ms)","trace[232351169] 'compare' (duration: 12.27714ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:04.073898Z","caller":"traceutil/trace.go:171","msg":"trace[1333997608] transaction","detail":"{read_only:false; response_revision:26990; number_of_response:1; }","duration":"375.503489ms","start":"2026-09-23T11:30:03.698365Z","end":"2026-09-23T11:30:04.073868Z","steps":["trace[1333997608] 'process raft request' (duration: 375.272433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:04.074096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:03.698332Z","time spent":"375.650642ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:04.074212Z","caller":"traceutil/trace.go:171","msg":"trace[2053317921] transaction","detail":"{read_only:false; response_revision:26991; number_of_response:1; }","duration":"372.348442ms","start":"2026-09-23T11:30:03.701835Z","end":"2026-09-23T11:30:04.074183Z","steps":["trace[2053317921] 'process raft request' (duration: 371.986302ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.074284Z","caller":"traceutil/trace.go:171","msg":"trace[1228917768] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"366.819529ms","start":"2026-09-23T11:30:03.707446Z","end":"2026-09-23T11:30:04.074265Z","steps":["trace[1228917768] 'process raft request' (duration: 366.651954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:04.074391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:03.701826Z","time spent":"372.461415ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:30:04.074391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:03.707426Z","time spent":"366.898341ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:04.074725Z","caller":"traceutil/trace.go:171","msg":"trace[1147901248] transaction","detail":"{read_only:false; response_revision:26993; number_of_response:1; }","duration":"365.373508ms","start":"2026-09-23T11:30:03.709341Z","end":"2026-09-23T11:30:04.074715Z","steps":["trace[1147901248] 'process raft request' (duration: 364.879715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:04.07478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:03.709327Z","time spent":"365.42569ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:30:04.313389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.740192ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-23T11:30:04.313526Z","caller":"traceutil/trace.go:171","msg":"trace[1551252969] linearizableReadLoop","detail":"{readStateIndex:27885; appliedIndex:27879; }","duration":"557.655978ms","start":"2026-09-23T11:30:03.755847Z","end":"2026-09-23T11:30:04.313503Z","steps":["trace[1551252969] 'read index received' (duration: 73.605762ms)","trace[1551252969] 'applied index is now lower than readState.Index' (duration: 484.048596ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:04.314144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.287726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-23T11:30:04.314189Z","caller":"traceutil/trace.go:171","msg":"trace[1944338293] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26993; }","duration":"558.382809ms","start":"2026-09-23T11:30:03.755792Z","end":"2026-09-23T11:30:04.314175Z","steps":["trace[1944338293] 'agreement among raft nodes before linearized reading' (duration: 558.194564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:04.314225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:03.755762Z","time spent":"558.453841ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-23T11:30:04.314209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.895117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-23T11:30:04.314238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.843129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-23T11:30:04.314323Z","caller":"traceutil/trace.go:171","msg":"trace[1707377239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26993; }","duration":"542.962393ms","start":"2026-09-23T11:30:03.771338Z","end":"2026-09-23T11:30:04.3143Z","steps":["trace[1707377239] 'agreement among raft nodes before linearized reading' (duration: 542.724867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:04.314375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:03.771327Z","time spent":"543.039425ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-23T11:30:04.314426Z","caller":"traceutil/trace.go:171","msg":"trace[1615843560] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:26993; }","duration":"226.058332ms","start":"2026-09-23T11:30:04.088228Z","end":"2026-09-23T11:30:04.314287Z","steps":["trace[1615843560] 'agreement among raft nodes before linearized reading' (duration: 225.832886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:04.31458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.760943ms","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-09-23T11:30:04.314619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.710737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:04.314623Z","caller":"traceutil/trace.go:171","msg":"trace[228966734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26993; }","duration":"284.851726ms","start":"2026-09-23T11:30:04.029758Z","end":"2026-09-23T11:30:04.31461Z","steps":["trace[228966734] 'agreement among raft nodes before linearized reading' (duration: 284.725602ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.314683Z","caller":"traceutil/trace.go:171","msg":"trace[294868660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26993; }","duration":"328.797229ms","start":"2026-09-23T11:30:03.985849Z","end":"2026-09-23T11:30:04.314647Z","steps":["trace[294868660] 'agreement among raft nodes before linearized reading' (duration: 328.735607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:04.314713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:03.985817Z","time spent":"328.890681ms","remote":"127.0.0.1:41458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T11:30:04.31492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.598499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-23T11:30:04.314976Z","caller":"traceutil/trace.go:171","msg":"trace[505487850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26993; }","duration":"154.700691ms","start":"2026-09-23T11:30:04.16026Z","end":"2026-09-23T11:30:04.31496Z","steps":["trace[505487850] 'agreement among raft nodes before linearized reading' (duration: 154.497046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:04.31494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.114782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-23T11:30:04.315295Z","caller":"traceutil/trace.go:171","msg":"trace[187576876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26993; }","duration":"147.513073ms","start":"2026-09-23T11:30:04.167732Z","end":"2026-09-23T11:30:04.315246Z","steps":["trace[187576876] 'agreement among raft nodes before linearized reading' (duration: 146.332681ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.561039Z","caller":"traceutil/trace.go:171","msg":"trace[1830380742] transaction","detail":"{read_only:false; response_revision:26995; number_of_response:1; }","duration":"240.583504ms","start":"2026-09-23T11:30:04.320423Z","end":"2026-09-23T11:30:04.561006Z","steps":["trace[1830380742] 'process raft request' (duration: 240.530772ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.561126Z","caller":"traceutil/trace.go:171","msg":"trace[1028245019] transaction","detail":"{read_only:false; response_revision:26994; number_of_response:1; }","duration":"240.642945ms","start":"2026-09-23T11:30:04.320425Z","end":"2026-09-23T11:30:04.561068Z","steps":["trace[1028245019] 'process raft request' (duration: 240.296606ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.60834Z","caller":"traceutil/trace.go:171","msg":"trace[776339773] transaction","detail":"{read_only:false; response_revision:26996; number_of_response:1; }","duration":"287.721115ms","start":"2026-09-23T11:30:04.320565Z","end":"2026-09-23T11:30:04.608286Z","steps":["trace[776339773] 'process raft request' (duration: 287.463768ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.608722Z","caller":"traceutil/trace.go:171","msg":"trace[737340660] transaction","detail":"{read_only:false; response_revision:26997; number_of_response:1; }","duration":"288.030244ms","start":"2026-09-23T11:30:04.320641Z","end":"2026-09-23T11:30:04.608671Z","steps":["trace[737340660] 'process raft request' (duration: 287.568061ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.608809Z","caller":"traceutil/trace.go:171","msg":"trace[1871883181] transaction","detail":"{read_only:false; response_revision:26998; number_of_response:1; }","duration":"288.077264ms","start":"2026-09-23T11:30:04.320695Z","end":"2026-09-23T11:30:04.608773Z","steps":["trace[1871883181] 'process raft request' (duration: 287.856598ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:04.919707Z","caller":"traceutil/trace.go:171","msg":"trace[492089628] transaction","detail":"{read_only:false; response_revision:26999; number_of_response:1; }","duration":"206.527192ms","start":"2026-09-23T11:30:04.713111Z","end":"2026-09-23T11:30:04.919638Z","steps":["trace[492089628] 'process raft request' (duration: 166.001123ms)","trace[492089628] 'compare' (duration: 40.038686ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:04.95686Z","caller":"traceutil/trace.go:171","msg":"trace[1772828847] transaction","detail":"{read_only:false; response_revision:27000; number_of_response:1; }","duration":"117.323219ms","start":"2026-09-23T11:30:04.839497Z","end":"2026-09-23T11:30:04.95682Z","steps":["trace[1772828847] 'process raft request' (duration: 117.065092ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.120974Z","caller":"traceutil/trace.go:171","msg":"trace[1788646118] transaction","detail":"{read_only:false; response_revision:27001; number_of_response:1; }","duration":"105.245216ms","start":"2026-09-23T11:30:05.015678Z","end":"2026-09-23T11:30:05.120923Z","steps":["trace[1788646118] 'process raft request' (duration: 104.906637ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:05.342858Z","caller":"traceutil/trace.go:171","msg":"trace[1642588078] transaction","detail":"{read_only:false; response_revision:27003; number_of_response:1; }","duration":"154.391953ms","start":"2026-09-23T11:30:05.188418Z","end":"2026-09-23T11:30:05.34281Z","steps":["trace[1642588078] 'process raft request' (duration: 69.651023ms)","trace[1642588078] 'compare' (duration: 84.36587ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:09.805972Z","caller":"traceutil/trace.go:171","msg":"trace[375648398] transaction","detail":"{read_only:false; response_revision:27040; number_of_response:1; }","duration":"116.842557ms","start":"2026-09-23T11:30:09.689086Z","end":"2026-09-23T11:30:09.805929Z","steps":["trace[375648398] 'process raft request' (duration: 116.350953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:10.120951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.615391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:10.12107Z","caller":"traceutil/trace.go:171","msg":"trace[1842239329] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:27041; }","duration":"107.797686ms","start":"2026-09-23T11:30:10.013248Z","end":"2026-09-23T11:30:10.121046Z","steps":["trace[1842239329] 'count revisions from in-memory index tree' (duration: 107.487468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:10.121053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.19443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:10.121149Z","caller":"traceutil/trace.go:171","msg":"trace[1360980130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27041; }","duration":"132.338314ms","start":"2026-09-23T11:30:09.988787Z","end":"2026-09-23T11:30:10.121126Z","steps":["trace[1360980130] 'range keys from in-memory index tree' (duration: 132.122849ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:10.334258Z","caller":"traceutil/trace.go:171","msg":"trace[1781435310] transaction","detail":"{read_only:false; response_revision:27044; number_of_response:1; }","duration":"232.822099ms","start":"2026-09-23T11:30:10.101416Z","end":"2026-09-23T11:30:10.334238Z","steps":["trace[1781435310] 'process raft request' (duration: 232.701306ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:10.33423Z","caller":"traceutil/trace.go:171","msg":"trace[880660613] transaction","detail":"{read_only:false; response_revision:27043; number_of_response:1; }","duration":"236.740817ms","start":"2026-09-23T11:30:10.097439Z","end":"2026-09-23T11:30:10.33418Z","steps":["trace[880660613] 'process raft request' (duration: 234.142026ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:10.334804Z","caller":"traceutil/trace.go:171","msg":"trace[962405776] linearizableReadLoop","detail":"{readStateIndex:27938; appliedIndex:27934; }","duration":"212.341994ms","start":"2026-09-23T11:30:10.122425Z","end":"2026-09-23T11:30:10.334767Z","steps":["trace[962405776] 'read index received' (duration: 64.09487ms)","trace[962405776] 'applied index is now lower than readState.Index' (duration: 148.245984ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:10.334931Z","caller":"traceutil/trace.go:171","msg":"trace[52061650] transaction","detail":"{read_only:false; response_revision:27046; number_of_response:1; }","duration":"208.544349ms","start":"2026-09-23T11:30:10.126365Z","end":"2026-09-23T11:30:10.33491Z","steps":["trace[52061650] 'process raft request' (duration: 208.277631ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:10.334975Z","caller":"traceutil/trace.go:171","msg":"trace[936558559] transaction","detail":"{read_only:false; response_revision:27045; number_of_response:1; }","duration":"229.586ms","start":"2026-09-23T11:30:10.105299Z","end":"2026-09-23T11:30:10.334885Z","steps":["trace[936558559] 'process raft request' (duration: 228.896971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:10.335043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.59555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:10.335105Z","caller":"traceutil/trace.go:171","msg":"trace[638599723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27046; }","duration":"212.724264ms","start":"2026-09-23T11:30:10.122363Z","end":"2026-09-23T11:30:10.335087Z","steps":["trace[638599723] 'agreement among raft nodes before linearized reading' (duration: 212.561509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:10.335334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.013801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-23T11:30:10.335451Z","caller":"traceutil/trace.go:171","msg":"trace[234236454] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:27046; }","duration":"205.202457ms","start":"2026-09-23T11:30:10.130222Z","end":"2026-09-23T11:30:10.335424Z","steps":["trace[234236454] 'agreement among raft nodes before linearized reading' (duration: 204.800736ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:12.560686Z","caller":"traceutil/trace.go:171","msg":"trace[954486645] linearizableReadLoop","detail":"{readStateIndex:27955; appliedIndex:27953; }","duration":"143.353149ms","start":"2026-09-23T11:30:12.417268Z","end":"2026-09-23T11:30:12.560621Z","steps":["trace[954486645] 'read index received' (duration: 48.129129ms)","trace[954486645] 'applied index is now lower than readState.Index' (duration: 95.222609ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:12.560878Z","caller":"traceutil/trace.go:171","msg":"trace[886094405] transaction","detail":"{read_only:false; response_revision:27061; number_of_response:1; }","duration":"219.427359ms","start":"2026-09-23T11:30:12.341402Z","end":"2026-09-23T11:30:12.56083Z","steps":["trace[886094405] 'process raft request' (duration: 124.070596ms)","trace[886094405] 'compare' (duration: 94.807358ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:12.561101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.796811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-23T11:30:12.561112Z","caller":"traceutil/trace.go:171","msg":"trace[474299917] transaction","detail":"{read_only:false; response_revision:27062; number_of_response:1; }","duration":"219.689176ms","start":"2026-09-23T11:30:12.341403Z","end":"2026-09-23T11:30:12.561092Z","steps":["trace[474299917] 'process raft request' (duration: 219.113991ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:12.561162Z","caller":"traceutil/trace.go:171","msg":"trace[1730676056] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:27062; }","duration":"143.961516ms","start":"2026-09-23T11:30:12.417181Z","end":"2026-09-23T11:30:12.561143Z","steps":["trace[1730676056] 'agreement among raft nodes before linearized reading' (duration: 143.599316ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:22.820282Z","caller":"traceutil/trace.go:171","msg":"trace[203378011] linearizableReadLoop","detail":"{readStateIndex:28042; appliedIndex:28041; }","duration":"104.953708ms","start":"2026-09-23T11:30:22.715288Z","end":"2026-09-23T11:30:22.820241Z","steps":["trace[203378011] 'read index received' (duration: 104.67137ms)","trace[203378011] 'applied index is now lower than readState.Index' (duration: 280.918µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:22.820407Z","caller":"traceutil/trace.go:171","msg":"trace[1413022029] transaction","detail":"{read_only:false; response_revision:27147; number_of_response:1; }","duration":"113.156803ms","start":"2026-09-23T11:30:22.707207Z","end":"2026-09-23T11:30:22.820364Z","steps":["trace[1413022029] 'process raft request' (duration: 112.769204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:22.82058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.236725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-23T11:30:22.820702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.123522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-23T11:30:22.820706Z","caller":"traceutil/trace.go:171","msg":"trace[1480923736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27147; }","duration":"105.4461ms","start":"2026-09-23T11:30:22.715236Z","end":"2026-09-23T11:30:22.820682Z","steps":["trace[1480923736] 'agreement among raft nodes before linearized reading' (duration: 105.150842ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:22.820749Z","caller":"traceutil/trace.go:171","msg":"trace[1978967688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27147; }","duration":"105.238545ms","start":"2026-09-23T11:30:22.715498Z","end":"2026-09-23T11:30:22.820736Z","steps":["trace[1978967688] 'agreement among raft nodes before linearized reading' (duration: 104.963397ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:28.386563Z","caller":"traceutil/trace.go:171","msg":"trace[1397521136] transaction","detail":"{read_only:false; response_revision:27194; number_of_response:1; }","duration":"110.602204ms","start":"2026-09-23T11:30:28.275884Z","end":"2026-09-23T11:30:28.386486Z","steps":["trace[1397521136] 'process raft request' (duration: 110.309566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:28.676781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.795442ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-09-23T11:30:28.677282Z","caller":"traceutil/trace.go:171","msg":"trace[1279698485] linearizableReadLoop","detail":"{readStateIndex:28093; appliedIndex:28091; }","duration":"249.777267ms","start":"2026-09-23T11:30:28.427482Z","end":"2026-09-23T11:30:28.677259Z","steps":["trace[1279698485] 'read index received' (duration: 85.424559ms)","trace[1279698485] 'applied index is now lower than readState.Index' (duration: 164.351628ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:28.677385Z","caller":"traceutil/trace.go:171","msg":"trace[267634699] transaction","detail":"{read_only:false; response_revision:27195; number_of_response:1; }","duration":"259.64639ms","start":"2026-09-23T11:30:28.417726Z","end":"2026-09-23T11:30:28.677373Z","steps":["trace[267634699] 'process raft request' (duration: 259.169977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:28.677693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.177218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:30:28.677803Z","caller":"traceutil/trace.go:171","msg":"trace[1353555384] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:27195; }","duration":"250.352103ms","start":"2026-09-23T11:30:28.427426Z","end":"2026-09-23T11:30:28.677778Z","steps":["trace[1353555384] 'agreement among raft nodes before linearized reading' (duration: 250.105326ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:28.994038Z","caller":"traceutil/trace.go:171","msg":"trace[7998516] linearizableReadLoop","detail":"{readStateIndex:28095; appliedIndex:28093; }","duration":"316.677434ms","start":"2026-09-23T11:30:28.677328Z","end":"2026-09-23T11:30:28.994005Z","steps":["trace[7998516] 'read index received' (duration: 14.187861ms)","trace[7998516] 'applied index is now lower than readState.Index' (duration: 302.487963ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:28.994089Z","caller":"traceutil/trace.go:171","msg":"trace[1795244218] transaction","detail":"{read_only:false; response_revision:27197; number_of_response:1; }","duration":"357.120461ms","start":"2026-09-23T11:30:28.636928Z","end":"2026-09-23T11:30:28.994048Z","steps":["trace[1795244218] 'process raft request' (duration: 356.768202ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:28.994205Z","caller":"traceutil/trace.go:171","msg":"trace[292751721] transaction","detail":"{read_only:false; response_revision:27198; number_of_response:1; }","duration":"314.313299ms","start":"2026-09-23T11:30:28.679878Z","end":"2026-09-23T11:30:28.994191Z","steps":["trace[292751721] 'process raft request' (duration: 314.057052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:28.994316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.636885Z","time spent":"357.312577ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:30:28.994374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.679839Z","time spent":"314.406972ms","remote":"127.0.0.1:41504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:30:28.994498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.034347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:30:28.994666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.708907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:28.994671Z","caller":"traceutil/trace.go:171","msg":"trace[1309599798] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:27198; }","duration":"429.212802ms","start":"2026-09-23T11:30:28.565438Z","end":"2026-09-23T11:30:28.994651Z","steps":["trace[1309599798] 'agreement among raft nodes before linearized reading' (duration: 428.948455ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:28.994704Z","caller":"traceutil/trace.go:171","msg":"trace[2071205454] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:27198; }","duration":"504.792179ms","start":"2026-09-23T11:30:28.489898Z","end":"2026-09-23T11:30:28.99469Z","steps":["trace[2071205454] 'agreement among raft nodes before linearized reading' (duration: 504.726007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:28.994716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.565358Z","time spent":"429.343515ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:30:28.994741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.489863Z","time spent":"504.870261ms","remote":"127.0.0.1:46806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:30:29.235299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.84708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:30:29.235462Z","caller":"traceutil/trace.go:171","msg":"trace[22443896] transaction","detail":"{read_only:false; response_revision:27199; number_of_response:1; }","duration":"426.99624ms","start":"2026-09-23T11:30:28.808431Z","end":"2026-09-23T11:30:29.235428Z","steps":["trace[22443896] 'process raft request' (duration: 273.902333ms)","trace[22443896] 'compare' (duration: 151.916405ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:29.235531Z","caller":"traceutil/trace.go:171","msg":"trace[140365238] transaction","detail":"{read_only:false; response_revision:27200; number_of_response:1; }","duration":"177.880982ms","start":"2026-09-23T11:30:29.057634Z","end":"2026-09-23T11:30:29.235515Z","steps":["trace[140365238] 'process raft request' (duration: 177.7964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:29.23561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.808399Z","time spent":"427.127244ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:30:29.235574Z","caller":"traceutil/trace.go:171","msg":"trace[247921969] linearizableReadLoop","detail":"{readStateIndex:28097; appliedIndex:28096; }","duration":"241.366415ms","start":"2026-09-23T11:30:28.994139Z","end":"2026-09-23T11:30:29.235505Z","steps":["trace[247921969] 'read index received' (duration: 88.206425ms)","trace[247921969] 'applied index is now lower than readState.Index' (duration: 153.155549ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:29.235846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.560764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-23T11:30:29.235941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.177146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-09-23T11:30:29.235885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.050386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-23T11:30:29.235971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.766449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:29.235987Z","caller":"traceutil/trace.go:171","msg":"trace[1897949167] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:27200; }","duration":"238.27707ms","start":"2026-09-23T11:30:28.997697Z","end":"2026-09-23T11:30:29.235974Z","steps":["trace[1897949167] 'agreement among raft nodes before linearized reading' (duration: 238.099885ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:29.235991Z","caller":"traceutil/trace.go:171","msg":"trace[853293754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27200; }","duration":"555.215441ms","start":"2026-09-23T11:30:28.680763Z","end":"2026-09-23T11:30:29.235979Z","steps":["trace[853293754] 'agreement among raft nodes before linearized reading' (duration: 554.884201ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:29.236013Z","caller":"traceutil/trace.go:171","msg":"trace[1347405019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27200; }","duration":"248.828031ms","start":"2026-09-23T11:30:28.98717Z","end":"2026-09-23T11:30:29.235998Z","steps":["trace[1347405019] 'agreement among raft nodes before linearized reading' (duration: 248.754689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:29.236038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.653037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-23T11:30:29.236045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.68075Z","time spent":"555.282923ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-23T11:30:29.235973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.566122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-23T11:30:29.236076Z","caller":"traceutil/trace.go:171","msg":"trace[1924538579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27200; }","duration":"549.725439ms","start":"2026-09-23T11:30:28.686339Z","end":"2026-09-23T11:30:29.236064Z","steps":["trace[1924538579] 'agreement among raft nodes before linearized reading' (duration: 549.654047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:29.236115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.686315Z","time spent":"549.792181ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T11:30:29.236062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.223052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:30:29.235943Z","caller":"traceutil/trace.go:171","msg":"trace[1403666369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27200; }","duration":"344.694058ms","start":"2026-09-23T11:30:28.891227Z","end":"2026-09-23T11:30:29.235921Z","steps":["trace[1403666369] 'agreement among raft nodes before linearized reading' (duration: 344.341328ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:29.23614Z","caller":"traceutil/trace.go:171","msg":"trace[1790474868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27200; }","duration":"366.720076ms","start":"2026-09-23T11:30:28.869379Z","end":"2026-09-23T11:30:29.236099Z","steps":["trace[1790474868] 'agreement among raft nodes before linearized reading' (duration: 366.532001ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:29.236181Z","caller":"traceutil/trace.go:171","msg":"trace[1914045464] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:27200; }","duration":"266.419757ms","start":"2026-09-23T11:30:28.969738Z","end":"2026-09-23T11:30:29.236158Z","steps":["trace[1914045464] 'agreement among raft nodes before linearized reading' (duration: 266.202341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:29.236229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.869368Z","time spent":"366.83598ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-23T11:30:29.236229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.891216Z","time spent":"344.988836ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-23T11:30:29.236447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.754311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-23T11:30:29.236579Z","caller":"traceutil/trace.go:171","msg":"trace[136692542] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27200; }","duration":"385.950307ms","start":"2026-09-23T11:30:28.850584Z","end":"2026-09-23T11:30:29.236534Z","steps":["trace[136692542] 'agreement among raft nodes before linearized reading' (duration: 385.420612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:30:29.236708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:30:28.850567Z","time spent":"386.122312ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-23T11:30:29.437982Z","caller":"traceutil/trace.go:171","msg":"trace[908237197] transaction","detail":"{read_only:false; response_revision:27202; number_of_response:1; }","duration":"195.589731ms","start":"2026-09-23T11:30:29.242352Z","end":"2026-09-23T11:30:29.437942Z","steps":["trace[908237197] 'process raft request' (duration: 136.879599ms)","trace[908237197] 'compare' (duration: 58.417293ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:30:29.43861Z","caller":"traceutil/trace.go:171","msg":"trace[351562394] transaction","detail":"{read_only:false; response_revision:27203; number_of_response:1; }","duration":"196.174367ms","start":"2026-09-23T11:30:29.242391Z","end":"2026-09-23T11:30:29.438565Z","steps":["trace[351562394] 'process raft request' (duration: 195.488918ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:29.438939Z","caller":"traceutil/trace.go:171","msg":"trace[1941982590] transaction","detail":"{read_only:false; response_revision:27205; number_of_response:1; }","duration":"195.55269ms","start":"2026-09-23T11:30:29.243358Z","end":"2026-09-23T11:30:29.438911Z","steps":["trace[1941982590] 'process raft request' (duration: 195.374695ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:29.4389Z","caller":"traceutil/trace.go:171","msg":"trace[50019680] linearizableReadLoop","detail":"{readStateIndex:28103; appliedIndex:28099; }","duration":"124.810086ms","start":"2026-09-23T11:30:29.314053Z","end":"2026-09-23T11:30:29.438864Z","steps":["trace[50019680] 'read index received' (duration: 65.087247ms)","trace[50019680] 'applied index is now lower than readState.Index' (duration: 59.721239ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:30:29.439221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.062393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:30:29.439275Z","caller":"traceutil/trace.go:171","msg":"trace[180055553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27205; }","duration":"125.28952ms","start":"2026-09-23T11:30:29.313973Z","end":"2026-09-23T11:30:29.439262Z","steps":["trace[180055553] 'agreement among raft nodes before linearized reading' (duration: 124.993892ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:29.439036Z","caller":"traceutil/trace.go:171","msg":"trace[546330018] transaction","detail":"{read_only:false; response_revision:27204; number_of_response:1; }","duration":"196.081235ms","start":"2026-09-23T11:30:29.242889Z","end":"2026-09-23T11:30:29.43897Z","steps":["trace[546330018] 'process raft request' (duration: 195.618562ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:31:04.750442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24724} {"level":"info","ts":"2026-09-23T11:31:04.913167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24724,"took":"157.691204ms","hash":4234677313,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":36401152,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-23T11:31:04.913275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4234677313,"revision":24724,"compact-revision":21704} {"level":"info","ts":"2026-09-23T11:33:34.719788Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c665390cd9eacc14","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:33:34.726971Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-23T11:33:34.727123Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-23T11:36:04.762125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27639} {"level":"info","ts":"2026-09-23T11:36:04.942806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27639,"took":"174.892759ms","hash":2308598434,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":34639872,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-23T11:36:04.942924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2308598434,"revision":27639,"compact-revision":24724} {"level":"info","ts":"2026-09-23T11:41:04.768058Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30336} {"level":"info","ts":"2026-09-23T11:41:04.925736Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30336,"took":"151.891506ms","hash":3770602326,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T11:41:04.925818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3770602326,"revision":30336,"compact-revision":27639} {"level":"info","ts":"2026-09-23T11:46:04.7763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32586} {"level":"info","ts":"2026-09-23T11:46:04.954504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32586,"took":"172.373432ms","hash":1535890541,"current-db-size-bytes":58400768,"current-db-size":"58 MB","current-db-size-in-use-bytes":30048256,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T11:46:04.954612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1535890541,"revision":32586,"compact-revision":30336} {"level":"info","ts":"2026-09-23T11:51:04.784083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34805} {"level":"info","ts":"2026-09-23T11:51:04.934127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34805,"took":"145.067203ms","hash":3408909930,"current-db-size-bytes":58400768,"current-db-size":"58 MB","current-db-size-in-use-bytes":30384128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T11:51:04.934212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3408909930,"revision":34805,"compact-revision":32586} {"level":"info","ts":"2026-09-23T11:54:50.146151Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c665390cd9eacc14","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:54:50.153947Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-23T11:54:50.154052Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-23T11:56:04.791372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37034} {"level":"info","ts":"2026-09-23T11:56:04.939294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37034,"took":"142.195985ms","hash":3631975716,"current-db-size-bytes":58822656,"current-db-size":"59 MB","current-db-size-in-use-bytes":32542720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T11:56:04.939344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3631975716,"revision":37034,"compact-revision":34805} {"level":"info","ts":"2026-09-23T12:01:04.798479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39374} {"level":"info","ts":"2026-09-23T12:01:04.952484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39374,"took":"149.547047ms","hash":4249658646,"current-db-size-bytes":61370368,"current-db-size":"61 MB","current-db-size-in-use-bytes":32493568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T12:01:04.952833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4249658646,"revision":39374,"compact-revision":37034}