{"level":"warn","ts":"2026-04-14T04:24:16.543259Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T04:24:16.543342Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-14T04:24:16.543407Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T04:24:16.543422Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-14T04:24:16.543439Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-14T04:24:16.544052Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-04-14T04:24:16.544125Z","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://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-14T04:24:16.548518Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.166776ms"} {"level":"info","ts":"2026-04-14T04:24:16.559205Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-04-14T04:24:16.55927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T04:24:16.559299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-04-14T04:24:16.559309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T04:24:16.559324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-04-14T04:24:16.559359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-04-14T04:24:16.564173Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T04:24:16.567016Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T04:24:16.568612Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-14T04:24:16.571164Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T04:24:16.571306Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T04:24:16.571421Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T04:24:16.571521Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T04:24:16.571532Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T04:24:16.573712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-04-14T04:24:16.574198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-14T04:24:16.575542Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-14T04:24:16.575738Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T04:24:16.575777Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T04:24:16.575821Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-14T04:24:16.575832Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-14T04:24:16.962892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T04:24:16.962946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T04:24:16.962967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-04-14T04:24:16.962978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-04-14T04:24:16.962983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-14T04:24:16.962997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-04-14T04:24:16.963011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-14T04:24:16.971048Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:24:16.972389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T04:24:16.972377Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T04:24:16.972464Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T04:24:16.972746Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T04:24:16.972807Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T04:24:16.972999Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:24:16.973198Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:24:16.97333Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:24:16.975975Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-04-14T04:24:16.976843Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T04:24:19.934279Z","caller":"traceutil/trace.go:171","msg":"trace[72322459] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"135.781803ms","start":"2026-04-14T04:24:19.798469Z","end":"2026-04-14T04:24:19.934251Z","steps":["trace[72322459] 'process raft request' (duration: 129.089586ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:24:34.455557Z","caller":"traceutil/trace.go:171","msg":"trace[548915131] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"119.264812ms","start":"2026-04-14T04:24:34.336267Z","end":"2026-04-14T04:24:34.455532Z","steps":["trace[548915131] 'process raft request' (duration: 119.128408ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:24:34.871089Z","caller":"traceutil/trace.go:171","msg":"trace[1095510832] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"181.316931ms","start":"2026-04-14T04:24:34.689734Z","end":"2026-04-14T04:24:34.87105Z","steps":["trace[1095510832] 'process raft request' (duration: 181.033104ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:24:52.084816Z","caller":"traceutil/trace.go:171","msg":"trace[574319983] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"153.709502ms","start":"2026-04-14T04:24:51.931087Z","end":"2026-04-14T04:24:52.084796Z","steps":["trace[574319983] 'read index received' (duration: 152.539909ms)","trace[574319983] 'applied index is now lower than readState.Index' (duration: 1.168673ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:24:52.084958Z","caller":"traceutil/trace.go:171","msg":"trace[2120793607] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"188.800839ms","start":"2026-04-14T04:24:51.896141Z","end":"2026-04-14T04:24:52.084942Z","steps":["trace[2120793607] 'process raft request' (duration: 187.661747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:24:52.085283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.183575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T04:24:52.085383Z","caller":"traceutil/trace.go:171","msg":"trace[1928493241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"154.324119ms","start":"2026-04-14T04:24:51.931041Z","end":"2026-04-14T04:24:52.085365Z","steps":["trace[1928493241] 'agreement among raft nodes before linearized reading' (duration: 154.111833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:24:52.776035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.396987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:24:52.776335Z","caller":"traceutil/trace.go:171","msg":"trace[10730428] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"216.46914ms","start":"2026-04-14T04:24:52.559842Z","end":"2026-04-14T04:24:52.776312Z","steps":["trace[10730428] 'process raft request' (duration: 216.411588ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:24:52.776407Z","caller":"traceutil/trace.go:171","msg":"trace[2025727967] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"409.090946ms","start":"2026-04-14T04:24:52.367241Z","end":"2026-04-14T04:24:52.776332Z","steps":["trace[2025727967] 'process raft request' (duration: 408.919852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:24:52.776741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.063728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T04:24:52.776797Z","caller":"traceutil/trace.go:171","msg":"trace[618205671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"240.164281ms","start":"2026-04-14T04:24:52.53662Z","end":"2026-04-14T04:24:52.776784Z","steps":["trace[618205671] 'agreement among raft nodes before linearized reading' (duration: 240.032696ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:24:52.776619Z","caller":"traceutil/trace.go:171","msg":"trace[1062468486] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"239.928393ms","start":"2026-04-14T04:24:52.536673Z","end":"2026-04-14T04:24:52.776602Z","steps":["trace[1062468486] 'read index received' (duration: 61.441µs)","trace[1062468486] 'applied index is now lower than readState.Index' (duration: 239.864952ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:24:52.776892Z","caller":"traceutil/trace.go:171","msg":"trace[1553964858] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"632.418807ms","start":"2026-04-14T04:24:52.144452Z","end":"2026-04-14T04:24:52.776871Z","steps":["trace[1553964858] 'process raft request' (duration: 241.189019ms)","trace[1553964858] 'compare' (duration: 389.114929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:24:52.777093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:24:52.144428Z","time spent":"632.613343ms","remote":"127.0.0.1:56990","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-04-14T04:24:52.777407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:24:52.367215Z","time spent":"409.478816ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:25:07.215824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.905346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rlqz5\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-14T04:25:07.215843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.27171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T04:25:07.215929Z","caller":"traceutil/trace.go:171","msg":"trace[1488700787] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rlqz5; range_end:; response_count:1; response_revision:769; }","duration":"101.106041ms","start":"2026-04-14T04:25:07.114803Z","end":"2026-04-14T04:25:07.215909Z","steps":["trace[1488700787] 'range keys from in-memory index tree' (duration: 100.72222ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:25:07.215943Z","caller":"traceutil/trace.go:171","msg":"trace[1823450574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"190.419864ms","start":"2026-04-14T04:25:07.025502Z","end":"2026-04-14T04:25:07.215922Z","steps":["trace[1823450574] 'range keys from in-memory index tree' (duration: 190.100945ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:26:21.68476Z","caller":"traceutil/trace.go:171","msg":"trace[1419309099] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"202.520331ms","start":"2026-04-14T04:26:21.482211Z","end":"2026-04-14T04:26:21.684732Z","steps":["trace[1419309099] 'read index received' (duration: 202.276664ms)","trace[1419309099] 'applied index is now lower than readState.Index' (duration: 242.677µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:26:21.684819Z","caller":"traceutil/trace.go:171","msg":"trace[362289063] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"230.14326ms","start":"2026-04-14T04:26:21.454654Z","end":"2026-04-14T04:26:21.684798Z","steps":["trace[362289063] 'process raft request' (duration: 229.918314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:26:21.685049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.28619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-883ebf6e-4bfc-4b21-a345-f7647bc22bab\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-14T04:26:21.685175Z","caller":"traceutil/trace.go:171","msg":"trace[685125651] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-883ebf6e-4bfc-4b21-a345-f7647bc22bab; range_end:; response_count:1; response_revision:1840; }","duration":"132.456785ms","start":"2026-04-14T04:26:21.552685Z","end":"2026-04-14T04:26:21.685142Z","steps":["trace[685125651] 'agreement among raft nodes before linearized reading' (duration: 132.194678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:26:21.685214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.963513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-04-14T04:26:21.685332Z","caller":"traceutil/trace.go:171","msg":"trace[725726623] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1840; }","duration":"203.138698ms","start":"2026-04-14T04:26:21.482168Z","end":"2026-04-14T04:26:21.685307Z","steps":["trace[725726623] 'agreement among raft nodes before linearized reading' (duration: 202.683895ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:26:21.807297Z","caller":"traceutil/trace.go:171","msg":"trace[1680221963] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"111.546891ms","start":"2026-04-14T04:26:21.695708Z","end":"2026-04-14T04:26:21.807255Z","steps":["trace[1680221963] 'process raft request' (duration: 95.250807ms)","trace[1680221963] 'compare' (duration: 16.127709ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:26:22.158595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.724086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:26:22.158754Z","caller":"traceutil/trace.go:171","msg":"trace[1638725827] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"344.688886ms","start":"2026-04-14T04:26:21.81405Z","end":"2026-04-14T04:26:22.158739Z","steps":["trace[1638725827] 'process raft request' (duration: 93.747865ms)","trace[1638725827] 'compare' (duration: 250.610422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:26:22.158807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:26:21.814036Z","time spent":"344.746448ms","remote":"127.0.0.1:56652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:26:22.159071Z","caller":"traceutil/trace.go:171","msg":"trace[362897965] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"344.10024ms","start":"2026-04-14T04:26:21.814945Z","end":"2026-04-14T04:26:22.159046Z","steps":["trace[362897965] 'process raft request' (duration: 343.73673ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:26:22.159118Z","caller":"traceutil/trace.go:171","msg":"trace[638381457] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"343.884924ms","start":"2026-04-14T04:26:21.815217Z","end":"2026-04-14T04:26:22.159102Z","steps":["trace[638381457] 'process raft request' (duration: 343.653198ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:26:22.159113Z","caller":"traceutil/trace.go:171","msg":"trace[1413795960] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"341.013695ms","start":"2026-04-14T04:26:21.818074Z","end":"2026-04-14T04:26:22.159088Z","steps":["trace[1413795960] 'process raft request' (duration: 340.911172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:26:22.159203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:26:21.815206Z","time spent":"343.948216ms","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:26:22.159217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:26:21.814933Z","time spent":"344.206483ms","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:26:22.159272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:26:21.818054Z","time spent":"341.128357ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:26:33.492457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.048763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:26:33.492534Z","caller":"traceutil/trace.go:171","msg":"trace[1206024181] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2015; }","duration":"141.646143ms","start":"2026-04-14T04:26:33.350878Z","end":"2026-04-14T04:26:33.492524Z","steps":["trace[1206024181] 'read index received' (duration: 13.358493ms)","trace[1206024181] 'applied index is now lower than readState.Index' (duration: 128.28695ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:26:33.492675Z","caller":"traceutil/trace.go:171","msg":"trace[1051005840] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"148.700319ms","start":"2026-04-14T04:26:33.343967Z","end":"2026-04-14T04:26:33.492667Z","steps":["trace[1051005840] 'process raft request' (duration: 20.348227ms)","trace[1051005840] 'compare' (duration: 127.813576ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:26:33.492894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.997454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:26:33.492864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.973372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:26:33.492922Z","caller":"traceutil/trace.go:171","msg":"trace[1051956231] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1945; }","duration":"113.051805ms","start":"2026-04-14T04:26:33.379862Z","end":"2026-04-14T04:26:33.492914Z","steps":["trace[1051956231] 'agreement among raft nodes before linearized reading' (duration: 113.007903ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:26:33.492942Z","caller":"traceutil/trace.go:171","msg":"trace[675311588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1945; }","duration":"142.092505ms","start":"2026-04-14T04:26:33.350834Z","end":"2026-04-14T04:26:33.492926Z","steps":["trace[675311588] 'agreement among raft nodes before linearized reading' (duration: 141.961841ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:05.823501Z","caller":"traceutil/trace.go:171","msg":"trace[117576170] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"114.70651ms","start":"2026-04-14T04:27:05.708768Z","end":"2026-04-14T04:27:05.823474Z","steps":["trace[117576170] 'process raft request' (duration: 114.546986ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:05.99102Z","caller":"traceutil/trace.go:171","msg":"trace[1950373330] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"144.939183ms","start":"2026-04-14T04:27:05.846052Z","end":"2026-04-14T04:27:05.990992Z","steps":["trace[1950373330] 'process raft request' (duration: 96.346417ms)","trace[1950373330] 'compare' (duration: 48.339149ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:27:11.700064Z","caller":"traceutil/trace.go:171","msg":"trace[1221812030] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"115.805658ms","start":"2026-04-14T04:27:11.584237Z","end":"2026-04-14T04:27:11.700042Z","steps":["trace[1221812030] 'process raft request' (duration: 115.649103ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:11.988407Z","caller":"traceutil/trace.go:171","msg":"trace[163517672] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"139.589023ms","start":"2026-04-14T04:27:11.848792Z","end":"2026-04-14T04:27:11.988381Z","steps":["trace[163517672] 'process raft request' (duration: 139.323395ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:12.341568Z","caller":"traceutil/trace.go:171","msg":"trace[1370609657] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2243; }","duration":"314.626758ms","start":"2026-04-14T04:27:12.026919Z","end":"2026-04-14T04:27:12.341545Z","steps":["trace[1370609657] 'read index received' (duration: 282.274614ms)","trace[1370609657] 'applied index is now lower than readState.Index' (duration: 32.351354ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:27:12.341617Z","caller":"traceutil/trace.go:171","msg":"trace[1973840969] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"331.394347ms","start":"2026-04-14T04:27:12.010164Z","end":"2026-04-14T04:27:12.341558Z","steps":["trace[1973840969] 'process raft request' (duration: 298.985282ms)","trace[1973840969] 'compare' (duration: 32.274952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:27:12.341966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.020131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T04:27:12.342051Z","caller":"traceutil/trace.go:171","msg":"trace[407110475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2165; }","duration":"315.158524ms","start":"2026-04-14T04:27:12.026875Z","end":"2026-04-14T04:27:12.342034Z","steps":["trace[407110475] 'agreement among raft nodes before linearized reading' (duration: 314.812454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:27:12.342022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:27:12.01014Z","time spent":"331.778669ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:27:12.342117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:27:12.026857Z","time spent":"315.243246ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T04:27:12.341843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.093194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-14T04:27:12.342294Z","caller":"traceutil/trace.go:171","msg":"trace[575668664] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2165; }","duration":"176.581938ms","start":"2026-04-14T04:27:12.165689Z","end":"2026-04-14T04:27:12.342271Z","steps":["trace[575668664] 'agreement among raft nodes before linearized reading' (duration: 176.024192ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:12.707179Z","caller":"traceutil/trace.go:171","msg":"trace[1030812209] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"329.851175ms","start":"2026-04-14T04:27:12.377233Z","end":"2026-04-14T04:27:12.707084Z","steps":["trace[1030812209] 'process raft request' (duration: 328.593449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:27:12.708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:27:12.377198Z","time spent":"330.516213ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:27:12.708438Z","caller":"traceutil/trace.go:171","msg":"trace[634622116] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2245; }","duration":"278.109287ms","start":"2026-04-14T04:27:12.430099Z","end":"2026-04-14T04:27:12.708208Z","steps":["trace[634622116] 'read index received' (duration: 276.230115ms)","trace[634622116] 'applied index is now lower than readState.Index' (duration: 1.875902ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:27:12.708737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.615962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:27:12.708803Z","caller":"traceutil/trace.go:171","msg":"trace[1181904098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2167; }","duration":"278.739285ms","start":"2026-04-14T04:27:12.430046Z","end":"2026-04-14T04:27:12.708785Z","steps":["trace[1181904098] 'agreement among raft nodes before linearized reading' (duration: 278.54222ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:12.71127Z","caller":"traceutil/trace.go:171","msg":"trace[1327133082] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"275.248297ms","start":"2026-04-14T04:27:12.435991Z","end":"2026-04-14T04:27:12.711239Z","steps":["trace[1327133082] 'process raft request' (duration: 274.63748ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:12.711376Z","caller":"traceutil/trace.go:171","msg":"trace[2128272688] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"168.754469ms","start":"2026-04-14T04:27:12.542505Z","end":"2026-04-14T04:27:12.711259Z","steps":["trace[2128272688] 'process raft request' (duration: 168.550424ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:28:03.373368Z","caller":"traceutil/trace.go:171","msg":"trace[1178074673] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"154.026768ms","start":"2026-04-14T04:28:03.21931Z","end":"2026-04-14T04:28:03.373337Z","steps":["trace[1178074673] 'process raft request' (duration: 153.863583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:30:58.839644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.186589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7ht8j\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-14T04:30:58.839735Z","caller":"traceutil/trace.go:171","msg":"trace[653112865] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7ht8j; range_end:; response_count:1; response_revision:3766; }","duration":"138.365505ms","start":"2026-04-14T04:30:58.701349Z","end":"2026-04-14T04:30:58.839715Z","steps":["trace[653112865] 'range keys from in-memory index tree' (duration: 138.006244ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:19.457071Z","caller":"traceutil/trace.go:171","msg":"trace[287299888] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"100.018542ms","start":"2026-04-14T04:32:19.357024Z","end":"2026-04-14T04:32:19.457043Z","steps":["trace[287299888] 'process raft request' (duration: 27.278523ms)","trace[287299888] 'compare' (duration: 72.607665ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:32:19.460388Z","caller":"traceutil/trace.go:171","msg":"trace[620799519] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"102.839301ms","start":"2026-04-14T04:32:19.357525Z","end":"2026-04-14T04:32:19.460364Z","steps":["trace[620799519] 'process raft request' (duration: 102.633695ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:19.460508Z","caller":"traceutil/trace.go:171","msg":"trace[2042480560] linearizableReadLoop","detail":"{readStateIndex:4939; appliedIndex:4937; }","duration":"101.713539ms","start":"2026-04-14T04:32:19.358771Z","end":"2026-04-14T04:32:19.460484Z","steps":["trace[2042480560] 'read index received' (duration: 25.544143ms)","trace[2042480560] 'applied index is now lower than readState.Index' (duration: 76.168516ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:32:19.460915Z","caller":"traceutil/trace.go:171","msg":"trace[1355245074] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"101.644969ms","start":"2026-04-14T04:32:19.359249Z","end":"2026-04-14T04:32:19.460894Z","steps":["trace[1355245074] 'process raft request' (duration: 101.119563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:32:19.46098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.189663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-14T04:32:19.461063Z","caller":"traceutil/trace.go:171","msg":"trace[1414492622] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"101.868894ms","start":"2026-04-14T04:32:19.359184Z","end":"2026-04-14T04:32:19.461053Z","steps":["trace[1414492622] 'process raft request' (duration: 101.086091ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:19.46107Z","caller":"traceutil/trace.go:171","msg":"trace[173063961] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4766; }","duration":"102.329787ms","start":"2026-04-14T04:32:19.358726Z","end":"2026-04-14T04:32:19.461055Z","steps":["trace[173063961] 'agreement among raft nodes before linearized reading' (duration: 102.026738ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:19.461149Z","caller":"traceutil/trace.go:171","msg":"trace[1099336227] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"101.911536ms","start":"2026-04-14T04:32:19.359227Z","end":"2026-04-14T04:32:19.461139Z","steps":["trace[1099336227] 'process raft request' (duration: 101.102322ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:19.461158Z","caller":"traceutil/trace.go:171","msg":"trace[872315145] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"101.910275ms","start":"2026-04-14T04:32:19.359219Z","end":"2026-04-14T04:32:19.461129Z","steps":["trace[872315145] 'process raft request' (duration: 101.084891ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:19.461354Z","caller":"traceutil/trace.go:171","msg":"trace[1723271032] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"102.038749ms","start":"2026-04-14T04:32:19.359305Z","end":"2026-04-14T04:32:19.461344Z","steps":["trace[1723271032] 'process raft request' (duration: 101.089222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:32:25.051504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.494714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fdcb1077-a958-4a11-afaf-75679a7aabc3\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-14T04:32:25.051666Z","caller":"traceutil/trace.go:171","msg":"trace[1533567263] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fdcb1077-a958-4a11-afaf-75679a7aabc3; range_end:; response_count:1; response_revision:4965; }","duration":"136.722321ms","start":"2026-04-14T04:32:24.91491Z","end":"2026-04-14T04:32:25.051632Z","steps":["trace[1533567263] 'range keys from in-memory index tree' (duration: 136.100154ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:25.403341Z","caller":"traceutil/trace.go:171","msg":"trace[489291098] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"142.141464ms","start":"2026-04-14T04:32:25.261175Z","end":"2026-04-14T04:32:25.403316Z","steps":["trace[489291098] 'read index received' (duration: 141.881947ms)","trace[489291098] 'applied index is now lower than readState.Index' (duration: 258.437µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:32:25.403441Z","caller":"traceutil/trace.go:171","msg":"trace[2056428892] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"148.621408ms","start":"2026-04-14T04:32:25.25479Z","end":"2026-04-14T04:32:25.403411Z","steps":["trace[2056428892] 'process raft request' (duration: 148.300279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:32:25.403599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.407512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7ht8j\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-14T04:32:25.403647Z","caller":"traceutil/trace.go:171","msg":"trace[1827917951] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7ht8j; range_end:; response_count:1; response_revision:4966; }","duration":"142.508214ms","start":"2026-04-14T04:32:25.261129Z","end":"2026-04-14T04:32:25.403637Z","steps":["trace[1827917951] 'agreement among raft nodes before linearized reading' (duration: 142.33809ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:34.533173Z","caller":"traceutil/trace.go:171","msg":"trace[74126858] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"102.389123ms","start":"2026-04-14T04:32:34.430761Z","end":"2026-04-14T04:32:34.53315Z","steps":["trace[74126858] 'process raft request' (duration: 102.066684ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:34.533206Z","caller":"traceutil/trace.go:171","msg":"trace[1544059323] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"103.229176ms","start":"2026-04-14T04:32:34.429944Z","end":"2026-04-14T04:32:34.533173Z","steps":["trace[1544059323] 'process raft request' (duration: 71.425064ms)","trace[1544059323] 'compare' (duration: 31.112123ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:32:42.887625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.034292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:32:42.887767Z","caller":"traceutil/trace.go:171","msg":"trace[1987614983] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"162.335692ms","start":"2026-04-14T04:32:42.725409Z","end":"2026-04-14T04:32:42.887745Z","steps":["trace[1987614983] 'process raft request' (duration: 60.090564ms)","trace[1987614983] 'compare' (duration: 101.901938ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:34:17.001867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3037} {"level":"info","ts":"2026-04-14T04:34:17.081544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3037,"took":"78.539038ms","hash":2004007426,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":32055296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T04:34:17.081608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2004007426,"revision":3037,"compact-revision":-1} {"level":"warn","ts":"2026-04-14T04:38:51.676361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.398438ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:38:51.676553Z","caller":"traceutil/trace.go:171","msg":"trace[746389997] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8183; }","duration":"152.625705ms","start":"2026-04-14T04:38:51.5239Z","end":"2026-04-14T04:38:51.676526Z","steps":["trace[746389997] 'range keys from in-memory index tree' (duration: 152.367297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:38:51.676688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.168958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-14T04:38:51.67678Z","caller":"traceutil/trace.go:171","msg":"trace[1057157392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8183; }","duration":"259.320153ms","start":"2026-04-14T04:38:51.417439Z","end":"2026-04-14T04:38:51.676759Z","steps":["trace[1057157392] 'range keys from in-memory index tree' (duration: 258.980543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:38:51.676838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.770161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7ht8j\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-14T04:38:51.676959Z","caller":"traceutil/trace.go:171","msg":"trace[923387738] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7ht8j; range_end:; response_count:1; response_revision:8183; }","duration":"183.953457ms","start":"2026-04-14T04:38:51.492976Z","end":"2026-04-14T04:38:51.67693Z","steps":["trace[923387738] 'range keys from in-memory index tree' (duration: 183.522474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:38:51.676965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.340387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T04:38:51.677044Z","caller":"traceutil/trace.go:171","msg":"trace[985879628] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8183; }","duration":"159.45645ms","start":"2026-04-14T04:38:51.517568Z","end":"2026-04-14T04:38:51.677024Z","steps":["trace[985879628] 'count revisions from in-memory index tree' (duration: 159.282095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:38:51.677048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.601077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-14T04:38:51.677117Z","caller":"traceutil/trace.go:171","msg":"trace[752723232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8183; }","duration":"261.70739ms","start":"2026-04-14T04:38:51.415392Z","end":"2026-04-14T04:38:51.677099Z","steps":["trace[752723232] 'range keys from in-memory index tree' (duration: 261.3376ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:02.738423Z","caller":"traceutil/trace.go:171","msg":"trace[110568887] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"220.447687ms","start":"2026-04-14T04:39:02.51795Z","end":"2026-04-14T04:39:02.738397Z","steps":["trace[110568887] 'process raft request' (duration: 220.291292ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:17.009037Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6179} {"level":"info","ts":"2026-04-14T04:39:17.107929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6179,"took":"96.722208ms","hash":553684727,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":22974464,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-14T04:39:17.108144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":553684727,"revision":6179,"compact-revision":3037} {"level":"info","ts":"2026-04-14T04:39:32.5183Z","caller":"traceutil/trace.go:171","msg":"trace[1870977264] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"251.940205ms","start":"2026-04-14T04:39:32.266347Z","end":"2026-04-14T04:39:32.518288Z","steps":["trace[1870977264] 'process raft request' (duration: 251.664677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:39:32.518713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.016264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:39:32.518766Z","caller":"traceutil/trace.go:171","msg":"trace[334977163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8459; }","duration":"166.130507ms","start":"2026-04-14T04:39:32.352624Z","end":"2026-04-14T04:39:32.518754Z","steps":["trace[334977163] 'agreement among raft nodes before linearized reading' (duration: 166.031424ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:32.51818Z","caller":"traceutil/trace.go:171","msg":"trace[1853090693] linearizableReadLoop","detail":"{readStateIndex:8755; appliedIndex:8754; }","duration":"165.467228ms","start":"2026-04-14T04:39:32.352694Z","end":"2026-04-14T04:39:32.518161Z","steps":["trace[1853090693] 'read index received' (duration: 165.247622ms)","trace[1853090693] 'applied index is now lower than readState.Index' (duration: 218.846µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:39:32.710088Z","caller":"traceutil/trace.go:171","msg":"trace[1843484960] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"185.268123ms","start":"2026-04-14T04:39:32.524782Z","end":"2026-04-14T04:39:32.71005Z","steps":["trace[1843484960] 'process raft request' (duration: 182.661168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:39:35.345377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.514186ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T04:39:35.347432Z","caller":"traceutil/trace.go:171","msg":"trace[1520737847] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"152.179329ms","start":"2026-04-14T04:39:35.195228Z","end":"2026-04-14T04:39:35.347407Z","steps":["trace[1520737847] 'process raft request' (duration: 151.915852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:39:45.45035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.787587ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-14T04:39:45.54083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.98343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:39:45.540989Z","caller":"traceutil/trace.go:171","msg":"trace[1243864030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8566; }","duration":"188.202357ms","start":"2026-04-14T04:39:45.352742Z","end":"2026-04-14T04:39:45.540945Z","steps":["trace[1243864030] 'agreement among raft nodes before linearized reading' (duration: 97.940123ms)","trace[1243864030] 'range keys from in-memory index tree' (duration: 90.034557ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:39:45.541038Z","caller":"traceutil/trace.go:171","msg":"trace[1403429629] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"156.978977ms","start":"2026-04-14T04:39:45.384027Z","end":"2026-04-14T04:39:45.541006Z","steps":["trace[1403429629] 'process raft request' (duration: 149.857033ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:45.541235Z","caller":"traceutil/trace.go:171","msg":"trace[1469707468] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"130.446271ms","start":"2026-04-14T04:39:45.410743Z","end":"2026-04-14T04:39:45.54119Z","steps":["trace[1469707468] 'process raft request' (duration: 130.156643ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:40:29.791291Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002442.wal"} {"level":"info","ts":"2026-04-14T04:41:49.535184Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T04:41:49.540786Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-14T04:41:49.540864Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-14T04:44:17.015473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8324} {"level":"info","ts":"2026-04-14T04:44:17.094717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8324,"took":"76.926848ms","hash":2257740309,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":26632192,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-14T04:44:17.095356Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2257740309,"revision":8324,"compact-revision":6179} {"level":"info","ts":"2026-04-14T04:49:17.022566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11006} {"level":"info","ts":"2026-04-14T04:49:17.113456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11006,"took":"88.092724ms","hash":1369886129,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":28344320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-14T04:49:17.113507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1369886129,"revision":11006,"compact-revision":8324} {"level":"warn","ts":"2026-04-14T04:50:50.239197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.188233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-04-14T04:50:50.239269Z","caller":"traceutil/trace.go:171","msg":"trace[1242505743] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:13634; }","duration":"101.317577ms","start":"2026-04-14T04:50:50.137936Z","end":"2026-04-14T04:50:50.239254Z","steps":["trace[1242505743] 'range keys from in-memory index tree' (duration: 101.028698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:50:50.23926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.688457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T04:50:50.24107Z","caller":"traceutil/trace.go:171","msg":"trace[524898176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13634; }","duration":"109.56021ms","start":"2026-04-14T04:50:50.13149Z","end":"2026-04-14T04:50:50.24105Z","steps":["trace[524898176] 'range keys from in-memory index tree' (duration: 107.514202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:51:05.031495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.217262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7530"} {"level":"info","ts":"2026-04-14T04:51:05.031566Z","caller":"traceutil/trace.go:171","msg":"trace[1933915340] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:13727; }","duration":"108.340697ms","start":"2026-04-14T04:51:04.92321Z","end":"2026-04-14T04:51:05.031551Z","steps":["trace[1933915340] 'range keys from in-memory index tree' (duration: 108.049648ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:51:06.09678Z","caller":"traceutil/trace.go:171","msg":"trace[1420891218] transaction","detail":"{read_only:false; response_revision:13732; number_of_response:1; }","duration":"191.134059ms","start":"2026-04-14T04:51:05.905616Z","end":"2026-04-14T04:51:06.09675Z","steps":["trace[1420891218] 'process raft request' (duration: 190.901822ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:51:06.096806Z","caller":"traceutil/trace.go:171","msg":"trace[1475387102] linearizableReadLoop","detail":"{readStateIndex:14197; appliedIndex:14196; }","duration":"133.254251ms","start":"2026-04-14T04:51:05.963514Z","end":"2026-04-14T04:51:06.096769Z","steps":["trace[1475387102] 'read index received' (duration: 133.040145ms)","trace[1475387102] 'applied index is now lower than readState.Index' (duration: 211.286µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:51:06.097067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.54364ms","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-04-14T04:51:06.097122Z","caller":"traceutil/trace.go:171","msg":"trace[676813345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13732; }","duration":"133.622942ms","start":"2026-04-14T04:51:05.963488Z","end":"2026-04-14T04:51:06.097111Z","steps":["trace[676813345] 'agreement among raft nodes before linearized reading' (duration: 133.402536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:51:28.111269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.105132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cddcf9c-6772-4e88-9d82-a69f70175d9a\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-04-14T04:51:28.111378Z","caller":"traceutil/trace.go:171","msg":"trace[420563526] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9cddcf9c-6772-4e88-9d82-a69f70175d9a; range_end:; response_count:1; response_revision:14036; }","duration":"235.272897ms","start":"2026-04-14T04:51:27.876077Z","end":"2026-04-14T04:51:28.111349Z","steps":["trace[420563526] 'range keys from in-memory index tree' (duration: 234.913946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:51:28.111368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.932627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cddcf9c-6772-4e88-9d82-a69f70175d9a\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-04-14T04:51:28.111491Z","caller":"traceutil/trace.go:171","msg":"trace[1067689228] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9cddcf9c-6772-4e88-9d82-a69f70175d9a; range_end:; response_count:1; response_revision:14036; }","duration":"353.124623ms","start":"2026-04-14T04:51:27.758349Z","end":"2026-04-14T04:51:28.111473Z","steps":["trace[1067689228] 'range keys from in-memory index tree' (duration: 352.755411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:51:28.111526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:51:27.75833Z","time spent":"353.190336ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3661,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cddcf9c-6772-4e88-9d82-a69f70175d9a\" "} {"level":"info","ts":"2026-04-14T04:54:17.028005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13042} {"level":"info","ts":"2026-04-14T04:54:17.104557Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13042,"took":"73.958704ms","hash":3051034341,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":31084544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-14T04:54:17.10463Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3051034341,"revision":13042,"compact-revision":11006} {"level":"info","ts":"2026-04-14T04:58:30.680346Z","caller":"traceutil/trace.go:171","msg":"trace[404341037] linearizableReadLoop","detail":"{readStateIndex:17965; appliedIndex:17964; }","duration":"127.700605ms","start":"2026-04-14T04:58:30.552623Z","end":"2026-04-14T04:58:30.680323Z","steps":["trace[404341037] 'read index received' (duration: 127.51191ms)","trace[404341037] 'applied index is now lower than readState.Index' (duration: 187.725µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:58:30.680609Z","caller":"traceutil/trace.go:171","msg":"trace[1821474942] transaction","detail":"{read_only:false; response_revision:17380; number_of_response:1; }","duration":"174.992304ms","start":"2026-04-14T04:58:30.505604Z","end":"2026-04-14T04:58:30.680597Z","steps":["trace[1821474942] 'process raft request' (duration: 174.589493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:58:30.680887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.25261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T04:58:30.680927Z","caller":"traceutil/trace.go:171","msg":"trace[1144708650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17380; }","duration":"128.355273ms","start":"2026-04-14T04:58:30.552562Z","end":"2026-04-14T04:58:30.680918Z","steps":["trace[1144708650] 'agreement among raft nodes before linearized reading' (duration: 128.226389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:58:30.681044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.563364ms","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-04-14T04:58:30.681125Z","caller":"traceutil/trace.go:171","msg":"trace[1444818649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17380; }","duration":"126.649026ms","start":"2026-04-14T04:58:30.554457Z","end":"2026-04-14T04:58:30.681106Z","steps":["trace[1444818649] 'agreement among raft nodes before linearized reading' (duration: 126.42224ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:58:36.819353Z","caller":"traceutil/trace.go:171","msg":"trace[1178111245] transaction","detail":"{read_only:false; response_revision:17413; number_of_response:1; }","duration":"107.305638ms","start":"2026-04-14T04:58:36.712025Z","end":"2026-04-14T04:58:36.81933Z","steps":["trace[1178111245] 'process raft request' (duration: 107.185905ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:58:37.48677Z","caller":"traceutil/trace.go:171","msg":"trace[1301666574] linearizableReadLoop","detail":"{readStateIndex:18000; appliedIndex:17999; }","duration":"134.031055ms","start":"2026-04-14T04:58:37.352715Z","end":"2026-04-14T04:58:37.486746Z","steps":["trace[1301666574] 'read index received' (duration: 133.797329ms)","trace[1301666574] 'applied index is now lower than readState.Index' (duration: 232.766µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:58:37.486856Z","caller":"traceutil/trace.go:171","msg":"trace[944512772] transaction","detail":"{read_only:false; response_revision:17414; number_of_response:1; }","duration":"578.687214ms","start":"2026-04-14T04:58:36.908104Z","end":"2026-04-14T04:58:37.486791Z","steps":["trace[944512772] 'process raft request' (duration: 578.483559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:58:37.486985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.243921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T04:58:37.487015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:58:36.908078Z","time spent":"578.849679ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20895,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:58:37.487024Z","caller":"traceutil/trace.go:171","msg":"trace[2113699854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17414; }","duration":"134.330634ms","start":"2026-04-14T04:58:37.352682Z","end":"2026-04-14T04:58:37.487013Z","steps":["trace[2113699854] 'agreement among raft nodes before linearized reading' (duration: 134.243851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:58:37.487075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.283632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T04:58:37.487246Z","caller":"traceutil/trace.go:171","msg":"trace[358160005] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:17414; }","duration":"134.540579ms","start":"2026-04-14T04:58:37.352682Z","end":"2026-04-14T04:58:37.487223Z","steps":["trace[358160005] 'agreement among raft nodes before linearized reading' (duration: 134.165209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:58:37.657578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.071064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:940404"} {"level":"info","ts":"2026-04-14T04:58:37.657643Z","caller":"traceutil/trace.go:171","msg":"trace[1637466778] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17414; }","duration":"100.204797ms","start":"2026-04-14T04:58:37.557425Z","end":"2026-04-14T04:58:37.65763Z","steps":["trace[1637466778] 'range keys from in-memory index tree' (duration: 99.395235ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:58:47.244667Z","caller":"traceutil/trace.go:171","msg":"trace[1693108716] transaction","detail":"{read_only:false; response_revision:17611; number_of_response:1; }","duration":"105.389184ms","start":"2026-04-14T04:58:47.13926Z","end":"2026-04-14T04:58:47.244649Z","steps":["trace[1693108716] 'process raft request' (duration: 105.357103ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:58:47.24498Z","caller":"traceutil/trace.go:171","msg":"trace[1752540160] transaction","detail":"{read_only:false; response_revision:17610; number_of_response:1; }","duration":"105.745634ms","start":"2026-04-14T04:58:47.139219Z","end":"2026-04-14T04:58:47.244964Z","steps":["trace[1752540160] 'process raft request' (duration: 105.356773ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:58:47.245072Z","caller":"traceutil/trace.go:171","msg":"trace[67390980] transaction","detail":"{read_only:false; response_revision:17609; number_of_response:1; }","duration":"106.072644ms","start":"2026-04-14T04:58:47.13899Z","end":"2026-04-14T04:58:47.245063Z","steps":["trace[67390980] 'process raft request' (duration: 97.602554ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:59:17.036488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15408} {"level":"info","ts":"2026-04-14T04:59:17.124487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15408,"took":"85.478639ms","hash":664544157,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":33488896,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-14T04:59:17.124554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":664544157,"revision":15408,"compact-revision":13042} {"level":"info","ts":"2026-04-14T05:02:25.91054Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T05:02:25.91583Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-14T05:02:25.916095Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-14T05:04:03.097714Z","caller":"traceutil/trace.go:171","msg":"trace[2028152536] transaction","detail":"{read_only:false; response_revision:20052; number_of_response:1; }","duration":"172.337667ms","start":"2026-04-14T05:04:02.925343Z","end":"2026-04-14T05:04:03.097681Z","steps":["trace[2028152536] 'process raft request' (duration: 171.966757ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:04:03.097845Z","caller":"traceutil/trace.go:171","msg":"trace[325473618] linearizableReadLoop","detail":"{readStateIndex:20722; appliedIndex:20719; }","duration":"168.179049ms","start":"2026-04-14T05:04:02.92964Z","end":"2026-04-14T05:04:03.097819Z","steps":["trace[325473618] 'read index received' (duration: 4.167067ms)","trace[325473618] 'applied index is now lower than readState.Index' (duration: 164.010732ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:04:03.097959Z","caller":"traceutil/trace.go:171","msg":"trace[1150356707] transaction","detail":"{read_only:false; response_revision:20053; number_of_response:1; }","duration":"171.931275ms","start":"2026-04-14T05:04:02.925991Z","end":"2026-04-14T05:04:03.097923Z","steps":["trace[1150356707] 'process raft request' (duration: 171.530594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:04:03.098941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.514258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-04-14T05:04:03.099055Z","caller":"traceutil/trace.go:171","msg":"trace[756046496] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:20053; }","duration":"169.440544ms","start":"2026-04-14T05:04:02.929584Z","end":"2026-04-14T05:04:03.099025Z","steps":["trace[756046496] 'agreement among raft nodes before linearized reading' (duration: 168.314243ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:04:17.047444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17946} {"level":"info","ts":"2026-04-14T05:04:17.173697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17946,"took":"122.33309ms","hash":834654783,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":30453760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T05:04:17.173765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":834654783,"revision":17946,"compact-revision":15408} {"level":"info","ts":"2026-04-14T05:04:19.098916Z","caller":"traceutil/trace.go:171","msg":"trace[1022265766] transaction","detail":"{read_only:false; response_revision:20135; number_of_response:1; }","duration":"110.746535ms","start":"2026-04-14T05:04:18.988149Z","end":"2026-04-14T05:04:19.098896Z","steps":["trace[1022265766] 'process raft request' (duration: 110.56851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:04:19.414562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.619558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:04:19.41491Z","caller":"traceutil/trace.go:171","msg":"trace[1830742109] linearizableReadLoop","detail":"{readStateIndex:20811; appliedIndex:20809; }","duration":"154.848992ms","start":"2026-04-14T05:04:19.260041Z","end":"2026-04-14T05:04:19.41489Z","steps":["trace[1830742109] 'read index received' (duration: 22.305941ms)","trace[1830742109] 'applied index is now lower than readState.Index' (duration: 132.541931ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:04:19.415263Z","caller":"traceutil/trace.go:171","msg":"trace[232198548] transaction","detail":"{read_only:false; response_revision:20136; number_of_response:1; }","duration":"264.664709ms","start":"2026-04-14T05:04:19.150533Z","end":"2026-04-14T05:04:19.415198Z","steps":["trace[232198548] 'process raft request' (duration: 105.30147ms)","trace[232198548] 'compare' (duration: 158.457223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:04:19.41533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.016921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T05:04:19.415368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.314325ms","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-04-14T05:04:19.415422Z","caller":"traceutil/trace.go:171","msg":"trace[544346583] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:20137; }","duration":"123.143395ms","start":"2026-04-14T05:04:19.292252Z","end":"2026-04-14T05:04:19.415395Z","steps":["trace[544346583] 'agreement among raft nodes before linearized reading' (duration: 122.901648ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:04:19.415479Z","caller":"traceutil/trace.go:171","msg":"trace[973937725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20137; }","duration":"155.439779ms","start":"2026-04-14T05:04:19.259991Z","end":"2026-04-14T05:04:19.41543Z","steps":["trace[973937725] 'agreement among raft nodes before linearized reading' (duration: 155.181641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:04:19.415925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.779882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-14T05:04:19.415983Z","caller":"traceutil/trace.go:171","msg":"trace[905257126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20137; }","duration":"147.860885ms","start":"2026-04-14T05:04:19.268106Z","end":"2026-04-14T05:04:19.415967Z","steps":["trace[905257126] 'agreement among raft nodes before linearized reading' (duration: 147.014431ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:04:19.415056Z","caller":"traceutil/trace.go:171","msg":"trace[379486335] transaction","detail":"{read_only:false; response_revision:20137; number_of_response:1; }","duration":"260.911874ms","start":"2026-04-14T05:04:19.154132Z","end":"2026-04-14T05:04:19.415044Z","steps":["trace[379486335] 'process raft request' (duration: 260.549533ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:04:19.674313Z","caller":"traceutil/trace.go:171","msg":"trace[713110221] transaction","detail":"{read_only:false; response_revision:20139; number_of_response:1; }","duration":"253.857213ms","start":"2026-04-14T05:04:19.420433Z","end":"2026-04-14T05:04:19.67429Z","steps":["trace[713110221] 'process raft request' (duration: 253.685168ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:04:19.683217Z","caller":"traceutil/trace.go:171","msg":"trace[168914904] transaction","detail":"{read_only:false; response_revision:20140; number_of_response:1; }","duration":"102.414138ms","start":"2026-04-14T05:04:19.580773Z","end":"2026-04-14T05:04:19.683187Z","steps":["trace[168914904] 'process raft request' (duration: 102.268904ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:04:20.087373Z","caller":"traceutil/trace.go:171","msg":"trace[262051453] transaction","detail":"{read_only:false; response_revision:20141; number_of_response:1; }","duration":"133.058796ms","start":"2026-04-14T05:04:19.954286Z","end":"2026-04-14T05:04:20.087345Z","steps":["trace[262051453] 'process raft request' (duration: 132.814478ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:08:03.147306Z","caller":"traceutil/trace.go:171","msg":"trace[1107783237] transaction","detail":"{read_only:false; response_revision:22535; number_of_response:1; }","duration":"289.11665ms","start":"2026-04-14T05:08:02.858159Z","end":"2026-04-14T05:08:03.147275Z","steps":["trace[1107783237] 'process raft request' (duration: 288.947826ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:08:03.264551Z","caller":"traceutil/trace.go:171","msg":"trace[1886465789] transaction","detail":"{read_only:false; response_revision:22536; number_of_response:1; }","duration":"110.498627ms","start":"2026-04-14T05:08:03.154008Z","end":"2026-04-14T05:08:03.264507Z","steps":["trace[1886465789] 'process raft request' (duration: 110.030704ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:08:03.264613Z","caller":"traceutil/trace.go:171","msg":"trace[1145227423] linearizableReadLoop","detail":"{readStateIndex:23280; appliedIndex:23279; }","duration":"109.650202ms","start":"2026-04-14T05:08:03.154531Z","end":"2026-04-14T05:08:03.264182Z","steps":["trace[1145227423] 'read index received' (duration: 109.431636ms)","trace[1145227423] 'applied index is now lower than readState.Index' (duration: 216.506µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:08:03.264884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.321301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5182"} {"level":"info","ts":"2026-04-14T05:08:03.264954Z","caller":"traceutil/trace.go:171","msg":"trace[1510121985] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:22536; }","duration":"110.459275ms","start":"2026-04-14T05:08:03.154477Z","end":"2026-04-14T05:08:03.264937Z","steps":["trace[1510121985] 'agreement among raft nodes before linearized reading' (duration: 110.168056ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:09:17.053869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20124} {"level":"info","ts":"2026-04-14T05:09:17.160935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20124,"took":"103.20443ms","hash":292595317,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":41664512,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-14T05:09:17.160981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":292595317,"revision":20124,"compact-revision":17946} {"level":"info","ts":"2026-04-14T05:11:21.679533Z","caller":"traceutil/trace.go:171","msg":"trace[5297168] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"193.690222ms","start":"2026-04-14T05:11:21.485827Z","end":"2026-04-14T05:11:21.679517Z","steps":["trace[5297168] 'process raft request' (duration: 193.60237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:22.022097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.137371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:11:22.022171Z","caller":"traceutil/trace.go:171","msg":"trace[1753589773] linearizableReadLoop","detail":"{readStateIndex:25389; appliedIndex:25388; }","duration":"289.530254ms","start":"2026-04-14T05:11:21.73263Z","end":"2026-04-14T05:11:22.022161Z","steps":["trace[1753589773] 'read index received' (duration: 181.2842ms)","trace[1753589773] 'applied index is now lower than readState.Index' (duration: 108.245244ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:22.02221Z","caller":"traceutil/trace.go:171","msg":"trace[2035960698] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"336.164004ms","start":"2026-04-14T05:11:21.686022Z","end":"2026-04-14T05:11:22.022186Z","steps":["trace[2035960698] 'process raft request' (duration: 227.880029ms)","trace[2035960698] 'compare' (duration: 107.822162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:22.022347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:21.686009Z","time spent":"336.259477ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:22.022241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.610727ms","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:6"} {"level":"info","ts":"2026-04-14T05:11:22.022491Z","caller":"traceutil/trace.go:171","msg":"trace[553628350] 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:24591; }","duration":"289.883364ms","start":"2026-04-14T05:11:21.732595Z","end":"2026-04-14T05:11:22.022478Z","steps":["trace[553628350] 'agreement among raft nodes before linearized reading' (duration: 289.619567ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:22.347981Z","caller":"traceutil/trace.go:171","msg":"trace[160561928] linearizableReadLoop","detail":"{readStateIndex:25390; appliedIndex:25389; }","duration":"325.75153ms","start":"2026-04-14T05:11:22.022185Z","end":"2026-04-14T05:11:22.347936Z","steps":["trace[160561928] 'read index received' (duration: 299.832186ms)","trace[160561928] 'applied index is now lower than readState.Index' (duration: 25.918374ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:22.348077Z","caller":"traceutil/trace.go:171","msg":"trace[1444898281] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"461.295634ms","start":"2026-04-14T05:11:21.886743Z","end":"2026-04-14T05:11:22.348038Z","steps":["trace[1444898281] 'process raft request' (duration: 435.32555ms)","trace[1444898281] 'compare' (duration: 25.527182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:22.348157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.648293ms","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-04-14T05:11:22.348194Z","caller":"traceutil/trace.go:171","msg":"trace[27187341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24592; }","duration":"539.719794ms","start":"2026-04-14T05:11:21.808464Z","end":"2026-04-14T05:11:22.348184Z","steps":["trace[27187341] 'agreement among raft nodes before linearized reading' (duration: 539.629622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:22.348204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:21.886724Z","time spent":"461.409508ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:22.348226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:21.808451Z","time spent":"539.766786ms","remote":"127.0.0.1:56728","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-04-14T05:11:22.348374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.697721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-14T05:11:22.348427Z","caller":"traceutil/trace.go:171","msg":"trace[1329000292] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:24592; }","duration":"211.793884ms","start":"2026-04-14T05:11:22.13662Z","end":"2026-04-14T05:11:22.348414Z","steps":["trace[1329000292] 'agreement among raft nodes before linearized reading' (duration: 211.578408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:22.348449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.987105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-14T05:11:22.348476Z","caller":"traceutil/trace.go:171","msg":"trace[1556379970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24592; }","duration":"115.051776ms","start":"2026-04-14T05:11:22.233417Z","end":"2026-04-14T05:11:22.348468Z","steps":["trace[1556379970] 'agreement among raft nodes before linearized reading' (duration: 114.938283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:22.601606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.782922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:11:22.601713Z","caller":"traceutil/trace.go:171","msg":"trace[1591229885] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"248.320128ms","start":"2026-04-14T05:11:22.353373Z","end":"2026-04-14T05:11:22.601693Z","steps":["trace[1591229885] 'process raft request' (duration: 147.374701ms)","trace[1591229885] 'compare' (duration: 100.462453ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:22.609397Z","caller":"traceutil/trace.go:171","msg":"trace[2023442257] linearizableReadLoop","detail":"{readStateIndex:25392; appliedIndex:25390; }","duration":"255.270255ms","start":"2026-04-14T05:11:22.35411Z","end":"2026-04-14T05:11:22.60938Z","steps":["trace[2023442257] 'read index received' (duration: 146.648681ms)","trace[2023442257] 'applied index is now lower than readState.Index' (duration: 108.620924ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:22.609453Z","caller":"traceutil/trace.go:171","msg":"trace[85901982] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"255.436709ms","start":"2026-04-14T05:11:22.353991Z","end":"2026-04-14T05:11:22.609428Z","steps":["trace[85901982] 'process raft request' (duration: 255.273084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:22.60962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.455841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:22.609701Z","caller":"traceutil/trace.go:171","msg":"trace[1796187686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24595; }","duration":"255.605094ms","start":"2026-04-14T05:11:22.354087Z","end":"2026-04-14T05:11:22.609692Z","steps":["trace[1796187686] 'agreement among raft nodes before linearized reading' (duration: 255.402729ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:22.610008Z","caller":"traceutil/trace.go:171","msg":"trace[418873349] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"157.55449ms","start":"2026-04-14T05:11:22.452443Z","end":"2026-04-14T05:11:22.609998Z","steps":["trace[418873349] 'process raft request' (duration: 156.908361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:22.62402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.014253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:11:22.624419Z","caller":"traceutil/trace.go:171","msg":"trace[1775289487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24596; }","duration":"104.450336ms","start":"2026-04-14T05:11:22.519957Z","end":"2026-04-14T05:11:22.624408Z","steps":["trace[1775289487] 'agreement among raft nodes before linearized reading' (duration: 104.003623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:22.927098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.234986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:11:22.927501Z","caller":"traceutil/trace.go:171","msg":"trace[1114082637] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"298.735546ms","start":"2026-04-14T05:11:22.628477Z","end":"2026-04-14T05:11:22.927212Z","steps":["trace[1114082637] 'process raft request' (duration: 133.283363ms)","trace[1114082637] 'compare' (duration: 165.047881ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:23.147224Z","caller":"traceutil/trace.go:171","msg":"trace[799971313] linearizableReadLoop","detail":"{readStateIndex:25397; appliedIndex:25395; }","duration":"379.009776ms","start":"2026-04-14T05:11:22.768189Z","end":"2026-04-14T05:11:23.147198Z","steps":["trace[799971313] 'read index received' (duration: 300.141824ms)","trace[799971313] 'applied index is now lower than readState.Index' (duration: 78.866842ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:23.147307Z","caller":"traceutil/trace.go:171","msg":"trace[1023040501] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"518.011921ms","start":"2026-04-14T05:11:22.629267Z","end":"2026-04-14T05:11:23.147279Z","steps":["trace[1023040501] 'process raft request' (duration: 439.049056ms)","trace[1023040501] 'compare' (duration: 78.566284ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:23.147351Z","caller":"traceutil/trace.go:171","msg":"trace[1166749458] transaction","detail":"{read_only:false; response_revision:24599; number_of_response:1; }","duration":"470.508536ms","start":"2026-04-14T05:11:22.676831Z","end":"2026-04-14T05:11:23.14734Z","steps":["trace[1166749458] 'process raft request' (duration: 470.228037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:23.147447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:22.629255Z","time spent":"518.121044ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:23.147603Z","caller":"traceutil/trace.go:171","msg":"trace[1518100475] transaction","detail":"{read_only:false; response_revision:24600; number_of_response:1; }","duration":"258.561628ms","start":"2026-04-14T05:11:22.889031Z","end":"2026-04-14T05:11:23.147593Z","steps":["trace[1518100475] 'process raft request' (duration: 258.128415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:23.14746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:22.676808Z","time spent":"470.572678ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:23.147847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.298498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:23.147885Z","caller":"traceutil/trace.go:171","msg":"trace[1311927118] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24601; }","duration":"171.341889ms","start":"2026-04-14T05:11:22.976532Z","end":"2026-04-14T05:11:23.147874Z","steps":["trace[1311927118] 'agreement among raft nodes before linearized reading' (duration: 171.262527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:23.147988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.79651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:23.14804Z","caller":"traceutil/trace.go:171","msg":"trace[723810926] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24601; }","duration":"379.876271ms","start":"2026-04-14T05:11:22.768149Z","end":"2026-04-14T05:11:23.148025Z","steps":["trace[723810926] 'agreement among raft nodes before linearized reading' (duration: 379.338276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:23.148077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:22.768133Z","time spent":"379.934794ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"info","ts":"2026-04-14T05:11:23.148143Z","caller":"traceutil/trace.go:171","msg":"trace[1829349309] transaction","detail":"{read_only:false; response_revision:24601; number_of_response:1; }","duration":"254.674808ms","start":"2026-04-14T05:11:22.893444Z","end":"2026-04-14T05:11:23.148119Z","steps":["trace[1829349309] 'process raft request' (duration: 254.106632ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:23.743441Z","caller":"traceutil/trace.go:171","msg":"trace[175791156] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"142.113171ms","start":"2026-04-14T05:11:23.601285Z","end":"2026-04-14T05:11:23.743398Z","steps":["trace[175791156] 'process raft request' (duration: 141.858454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:24.296444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.532307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:24.296678Z","caller":"traceutil/trace.go:171","msg":"trace[962171089] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24604; }","duration":"323.832045ms","start":"2026-04-14T05:11:23.972806Z","end":"2026-04-14T05:11:24.296638Z","steps":["trace[962171089] 'range keys from in-memory index tree' (duration: 323.449015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:24.296767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:23.972788Z","time spent":"323.956199ms","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-04-14T05:11:24.297627Z","caller":"traceutil/trace.go:171","msg":"trace[81858363] linearizableReadLoop","detail":"{readStateIndex:25403; appliedIndex:25402; }","duration":"266.563645ms","start":"2026-04-14T05:11:24.031042Z","end":"2026-04-14T05:11:24.297606Z","steps":["trace[81858363] 'read index received' (duration: 266.39725ms)","trace[81858363] 'applied index is now lower than readState.Index' (duration: 165.895µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:24.297892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.280111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:24.29792Z","caller":"traceutil/trace.go:171","msg":"trace[867176914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24605; }","duration":"110.373413ms","start":"2026-04-14T05:11:24.187539Z","end":"2026-04-14T05:11:24.297912Z","steps":["trace[867176914] 'agreement among raft nodes before linearized reading' (duration: 110.312741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:24.29789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.812791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:24.297968Z","caller":"traceutil/trace.go:171","msg":"trace[901136218] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24605; }","duration":"266.942935ms","start":"2026-04-14T05:11:24.031007Z","end":"2026-04-14T05:11:24.29795Z","steps":["trace[901136218] 'agreement among raft nodes before linearized reading' (duration: 266.781351ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:24.297964Z","caller":"traceutil/trace.go:171","msg":"trace[2098435813] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"268.672314ms","start":"2026-04-14T05:11:24.029263Z","end":"2026-04-14T05:11:24.297935Z","steps":["trace[2098435813] 'process raft request' (duration: 268.229201ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:24.733699Z","caller":"traceutil/trace.go:171","msg":"trace[2012939211] linearizableReadLoop","detail":"{readStateIndex:25404; appliedIndex:25403; }","duration":"254.528234ms","start":"2026-04-14T05:11:24.479124Z","end":"2026-04-14T05:11:24.733652Z","steps":["trace[2012939211] 'read index received' (duration: 249.494281ms)","trace[2012939211] 'applied index is now lower than readState.Index' (duration: 5.032693ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:24.733813Z","caller":"traceutil/trace.go:171","msg":"trace[305040331] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"378.561724ms","start":"2026-04-14T05:11:24.35523Z","end":"2026-04-14T05:11:24.733792Z","steps":["trace[305040331] 'process raft request' (duration: 373.372407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:24.733919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:24.355213Z","time spent":"378.648887ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:24.733935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.777261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:24.733975Z","caller":"traceutil/trace.go:171","msg":"trace[1774827008] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24606; }","duration":"254.869854ms","start":"2026-04-14T05:11:24.479092Z","end":"2026-04-14T05:11:24.733962Z","steps":["trace[1774827008] 'agreement among raft nodes before linearized reading' (duration: 254.72649ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:25.024155Z","caller":"traceutil/trace.go:171","msg":"trace[135120996] linearizableReadLoop","detail":"{readStateIndex:25406; appliedIndex:25404; }","duration":"290.353417ms","start":"2026-04-14T05:11:24.733784Z","end":"2026-04-14T05:11:25.024138Z","steps":["trace[135120996] 'read index received' (duration: 266.653396ms)","trace[135120996] 'applied index is now lower than readState.Index' (duration: 23.699211ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:25.024189Z","caller":"traceutil/trace.go:171","msg":"trace[1338901372] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"409.5151ms","start":"2026-04-14T05:11:24.614643Z","end":"2026-04-14T05:11:25.024158Z","steps":["trace[1338901372] 'process raft request' (duration: 385.865911ms)","trace[1338901372] 'compare' (duration: 22.851977ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:25.024283Z","caller":"traceutil/trace.go:171","msg":"trace[1714203818] transaction","detail":"{read_only:false; response_revision:24608; number_of_response:1; }","duration":"394.290129ms","start":"2026-04-14T05:11:24.629979Z","end":"2026-04-14T05:11:25.024269Z","steps":["trace[1714203818] 'process raft request' (duration: 394.108253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:25.024344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:24.614607Z","time spent":"409.649285ms","remote":"127.0.0.1:56728","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-04-14T05:11:25.024383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:24.629957Z","time spent":"394.352851ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:25.024493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.687532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T05:11:25.024571Z","caller":"traceutil/trace.go:171","msg":"trace[976727477] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:24608; }","duration":"517.827746ms","start":"2026-04-14T05:11:24.506726Z","end":"2026-04-14T05:11:25.024554Z","steps":["trace[976727477] 'agreement among raft nodes before linearized reading' (duration: 517.61231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:25.024634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:24.506691Z","time spent":"517.931939ms","remote":"127.0.0.1:33016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":32,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-04-14T05:11:25.024649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.020043ms","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-04-14T05:11:25.024721Z","caller":"traceutil/trace.go:171","msg":"trace[1264067987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24608; }","duration":"411.169258ms","start":"2026-04-14T05:11:24.613536Z","end":"2026-04-14T05:11:25.024706Z","steps":["trace[1264067987] 'agreement among raft nodes before linearized reading' (duration: 410.976772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:25.024787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.546907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-14T05:11:25.024813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:24.613506Z","time spent":"411.29464ms","remote":"127.0.0.1:56728","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":"info","ts":"2026-04-14T05:11:25.024825Z","caller":"traceutil/trace.go:171","msg":"trace[1832851599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24608; }","duration":"418.621159ms","start":"2026-04-14T05:11:24.606192Z","end":"2026-04-14T05:11:25.024813Z","steps":["trace[1832851599] 'agreement among raft nodes before linearized reading' (duration: 418.486235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:25.02486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:24.606169Z","time spent":"418.684331ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T05:11:31.082863Z","caller":"traceutil/trace.go:171","msg":"trace[1934084401] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"223.99918ms","start":"2026-04-14T05:11:30.858802Z","end":"2026-04-14T05:11:31.082801Z","steps":["trace[1934084401] 'process raft request' (duration: 141.767413ms)","trace[1934084401] 'compare' (duration: 82.025241ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:31.123203Z","caller":"traceutil/trace.go:171","msg":"trace[1783945092] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"150.447039ms","start":"2026-04-14T05:11:30.972711Z","end":"2026-04-14T05:11:31.123158Z","steps":["trace[1783945092] 'process raft request' (duration: 150.229132ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:31.474333Z","caller":"traceutil/trace.go:171","msg":"trace[1815515364] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"431.41726ms","start":"2026-04-14T05:11:31.04288Z","end":"2026-04-14T05:11:31.474297Z","steps":["trace[1815515364] 'process raft request' (duration: 332.064018ms)","trace[1815515364] 'compare' (duration: 98.724724ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:31.474394Z","caller":"traceutil/trace.go:171","msg":"trace[566018983] linearizableReadLoop","detail":"{readStateIndex:25462; appliedIndex:25459; }","duration":"416.537549ms","start":"2026-04-14T05:11:31.057821Z","end":"2026-04-14T05:11:31.474358Z","steps":["trace[566018983] 'read index received' (duration: 65.131384ms)","trace[566018983] 'applied index is now lower than readState.Index' (duration: 351.405045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:31.474475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.042842Z","time spent":"431.556764ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:31.474433Z","caller":"traceutil/trace.go:171","msg":"trace[680611148] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"428.041184ms","start":"2026-04-14T05:11:31.046338Z","end":"2026-04-14T05:11:31.474379Z","steps":["trace[680611148] 'process raft request' (duration: 427.464708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.474668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.824137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-14T05:11:31.474727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.046313Z","time spent":"428.24579ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:31.474831Z","caller":"traceutil/trace.go:171","msg":"trace[316809394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24661; }","duration":"417.013422ms","start":"2026-04-14T05:11:31.057779Z","end":"2026-04-14T05:11:31.474792Z","steps":["trace[316809394] 'agreement among raft nodes before linearized reading' (duration: 416.668552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.474895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.057767Z","time spent":"417.120705ms","remote":"127.0.0.1:56728","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-04-14T05:11:31.502046Z","caller":"traceutil/trace.go:171","msg":"trace[1232879817] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"245.765786ms","start":"2026-04-14T05:11:31.25623Z","end":"2026-04-14T05:11:31.501996Z","steps":["trace[1232879817] 'process raft request' (duration: 245.706534ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:31.504334Z","caller":"traceutil/trace.go:171","msg":"trace[908379312] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"328.532389ms","start":"2026-04-14T05:11:31.175787Z","end":"2026-04-14T05:11:31.504319Z","steps":["trace[908379312] 'process raft request' (duration: 325.925365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.504462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.175759Z","time spent":"328.614941ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:31.504558Z","caller":"traceutil/trace.go:171","msg":"trace[92164933] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"328.57305ms","start":"2026-04-14T05:11:31.175975Z","end":"2026-04-14T05:11:31.504548Z","steps":["trace[92164933] 'process raft request' (duration: 325.885214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.504635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.175966Z","time spent":"328.617891ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:31.504647Z","caller":"traceutil/trace.go:171","msg":"trace[933118356] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"328.467376ms","start":"2026-04-14T05:11:31.176152Z","end":"2026-04-14T05:11:31.504619Z","steps":["trace[933118356] 'process raft request' (duration: 325.747759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.504751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.176139Z","time spent":"328.556988ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:31.504816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.021279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T05:11:31.50476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.747173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:31.504857Z","caller":"traceutil/trace.go:171","msg":"trace[467122300] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24665; }","duration":"113.099302ms","start":"2026-04-14T05:11:31.391745Z","end":"2026-04-14T05:11:31.504845Z","steps":["trace[467122300] 'agreement among raft nodes before linearized reading' (duration: 113.03979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.504859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.148534ms","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-04-14T05:11:31.5049Z","caller":"traceutil/trace.go:171","msg":"trace[1997548976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24665; }","duration":"152.884557ms","start":"2026-04-14T05:11:31.351979Z","end":"2026-04-14T05:11:31.504863Z","steps":["trace[1997548976] 'agreement among raft nodes before linearized reading' (duration: 152.749063ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:31.504928Z","caller":"traceutil/trace.go:171","msg":"trace[808035003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24665; }","duration":"442.264198ms","start":"2026-04-14T05:11:31.062651Z","end":"2026-04-14T05:11:31.504915Z","steps":["trace[808035003] 'agreement among raft nodes before linearized reading' (duration: 442.080812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.504956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.062629Z","time spent":"442.321239ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T05:11:31.50498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.758673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-14T05:11:31.505042Z","caller":"traceutil/trace.go:171","msg":"trace[1979680182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24665; }","duration":"441.838026ms","start":"2026-04-14T05:11:31.063187Z","end":"2026-04-14T05:11:31.505025Z","steps":["trace[1979680182] 'agreement among raft nodes before linearized reading' (duration: 441.64625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.50508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.063177Z","time spent":"441.894987ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T05:11:31.505314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.473373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T05:11:31.505389Z","caller":"traceutil/trace.go:171","msg":"trace[511080456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24665; }","duration":"324.591856ms","start":"2026-04-14T05:11:31.180778Z","end":"2026-04-14T05:11:31.50537Z","steps":["trace[511080456] 'agreement among raft nodes before linearized reading' (duration: 323.980789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.506011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:31.180752Z","time spent":"325.249445ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T05:11:31.686237Z","caller":"traceutil/trace.go:171","msg":"trace[346190383] linearizableReadLoop","detail":"{readStateIndex:25467; appliedIndex:25466; }","duration":"180.335494ms","start":"2026-04-14T05:11:31.505884Z","end":"2026-04-14T05:11:31.686219Z","steps":["trace[346190383] 'read index received' (duration: 122.445846ms)","trace[346190383] 'applied index is now lower than readState.Index' (duration: 57.888928ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:31.686261Z","caller":"traceutil/trace.go:171","msg":"trace[2143132925] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"169.782416ms","start":"2026-04-14T05:11:31.516465Z","end":"2026-04-14T05:11:31.686247Z","steps":["trace[2143132925] 'process raft request' (duration: 169.749255ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:31.686379Z","caller":"traceutil/trace.go:171","msg":"trace[1532492678] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"176.219737ms","start":"2026-04-14T05:11:31.510154Z","end":"2026-04-14T05:11:31.686373Z","steps":["trace[1532492678] 'process raft request' (duration: 175.919329ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:31.686459Z","caller":"traceutil/trace.go:171","msg":"trace[746785410] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"207.443011ms","start":"2026-04-14T05:11:31.479009Z","end":"2026-04-14T05:11:31.686452Z","steps":["trace[746785410] 'process raft request' (duration: 149.199832ms)","trace[746785410] 'compare' (duration: 57.688012ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:31.68645Z","caller":"traceutil/trace.go:171","msg":"trace[1343600012] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"176.201977ms","start":"2026-04-14T05:11:31.510205Z","end":"2026-04-14T05:11:31.686407Z","steps":["trace[1343600012] 'process raft request' (duration: 175.978111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.686521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.622732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:31.687086Z","caller":"traceutil/trace.go:171","msg":"trace[1080282002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24669; }","duration":"181.212359ms","start":"2026-04-14T05:11:31.505855Z","end":"2026-04-14T05:11:31.687068Z","steps":["trace[1080282002] 'agreement among raft nodes before linearized reading' (duration: 180.625702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.937665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.177473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:31.937747Z","caller":"traceutil/trace.go:171","msg":"trace[1286482979] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24669; }","duration":"136.311227ms","start":"2026-04-14T05:11:31.801416Z","end":"2026-04-14T05:11:31.937727Z","steps":["trace[1286482979] 'count revisions from in-memory index tree' (duration: 136.100052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:31.937768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.778764ms","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-04-14T05:11:31.93783Z","caller":"traceutil/trace.go:171","msg":"trace[1120891187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24669; }","duration":"140.882757ms","start":"2026-04-14T05:11:31.796933Z","end":"2026-04-14T05:11:31.937816Z","steps":["trace[1120891187] 'range keys from in-memory index tree' (duration: 140.63089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:32.373584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.154964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:32.373641Z","caller":"traceutil/trace.go:171","msg":"trace[510253903] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24670; }","duration":"118.253567ms","start":"2026-04-14T05:11:32.255374Z","end":"2026-04-14T05:11:32.373627Z","steps":["trace[510253903] 'count revisions from in-memory index tree' (duration: 118.086792ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:32.501547Z","caller":"traceutil/trace.go:171","msg":"trace[1882502240] linearizableReadLoop","detail":"{readStateIndex:25472; appliedIndex:25471; }","duration":"148.932606ms","start":"2026-04-14T05:11:32.352588Z","end":"2026-04-14T05:11:32.501521Z","steps":["trace[1882502240] 'read index received' (duration: 148.734721ms)","trace[1882502240] 'applied index is now lower than readState.Index' (duration: 196.945µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:32.501621Z","caller":"traceutil/trace.go:171","msg":"trace[1324598166] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"176.213777ms","start":"2026-04-14T05:11:32.325371Z","end":"2026-04-14T05:11:32.501585Z","steps":["trace[1324598166] 'process raft request' (duration: 176.009781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:32.501726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.123011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:32.501803Z","caller":"traceutil/trace.go:171","msg":"trace[503882472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24671; }","duration":"149.244935ms","start":"2026-04-14T05:11:32.35255Z","end":"2026-04-14T05:11:32.501795Z","steps":["trace[503882472] 'agreement among raft nodes before linearized reading' (duration: 149.116561ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:33.878866Z","caller":"traceutil/trace.go:171","msg":"trace[1925010902] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"398.122818ms","start":"2026-04-14T05:11:33.480715Z","end":"2026-04-14T05:11:33.878838Z","steps":["trace[1925010902] 'process raft request' (duration: 397.728817ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:33.878887Z","caller":"traceutil/trace.go:171","msg":"trace[531173489] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"365.241667ms","start":"2026-04-14T05:11:33.513615Z","end":"2026-04-14T05:11:33.878856Z","steps":["trace[531173489] 'process raft request' (duration: 365.144444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:33.879017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:33.480671Z","time spent":"398.265761ms","remote":"127.0.0.1:56728","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-04-14T05:11:33.879017Z","caller":"traceutil/trace.go:171","msg":"trace[805191593] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"365.838233ms","start":"2026-04-14T05:11:33.513158Z","end":"2026-04-14T05:11:33.878996Z","steps":["trace[805191593] 'process raft request' (duration: 365.569985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:33.879072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:33.5136Z","time spent":"365.36794ms","remote":"127.0.0.1:56728","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-04-14T05:11:33.878917Z","caller":"traceutil/trace.go:171","msg":"trace[1975790181] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"367.252774ms","start":"2026-04-14T05:11:33.511628Z","end":"2026-04-14T05:11:33.878881Z","steps":["trace[1975790181] 'process raft request' (duration: 367.057628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:33.879181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:33.513135Z","time spent":"365.949356ms","remote":"127.0.0.1:56728","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-04-14T05:11:33.879173Z","caller":"traceutil/trace.go:171","msg":"trace[1823992397] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"368.090447ms","start":"2026-04-14T05:11:33.511065Z","end":"2026-04-14T05:11:33.879156Z","steps":["trace[1823992397] 'process raft request' (duration: 367.531012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:33.879342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:33.511036Z","time spent":"368.263182ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:33.879365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:33.511616Z","time spent":"367.660735ms","remote":"127.0.0.1:56728","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-04-14T05:11:33.920764Z","caller":"traceutil/trace.go:171","msg":"trace[1357714376] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"291.241623ms","start":"2026-04-14T05:11:33.629483Z","end":"2026-04-14T05:11:33.920724Z","steps":["trace[1357714376] 'process raft request' (duration: 291.006386ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:33.920934Z","caller":"traceutil/trace.go:171","msg":"trace[1006823064] linearizableReadLoop","detail":"{readStateIndex:25480; appliedIndex:25479; }","duration":"227.963691ms","start":"2026-04-14T05:11:33.692951Z","end":"2026-04-14T05:11:33.920915Z","steps":["trace[1006823064] 'read index received' (duration: 186.405715ms)","trace[1006823064] 'applied index is now lower than readState.Index' (duration: 41.555966ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:33.920714Z","caller":"traceutil/trace.go:171","msg":"trace[1278398435] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"129.897436ms","start":"2026-04-14T05:11:33.790795Z","end":"2026-04-14T05:11:33.920693Z","steps":["trace[1278398435] 'process raft request' (duration: 129.819224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:33.921113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.149376ms","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":"warn","ts":"2026-04-14T05:11:33.92127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.825238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T05:11:33.921326Z","caller":"traceutil/trace.go:171","msg":"trace[1464781188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24681; }","duration":"227.90778ms","start":"2026-04-14T05:11:33.693408Z","end":"2026-04-14T05:11:33.921315Z","steps":["trace[1464781188] 'agreement among raft nodes before linearized reading' (duration: 227.741305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:33.921149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.750436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-14T05:11:33.921433Z","caller":"traceutil/trace.go:171","msg":"trace[2063793417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24681; }","duration":"228.049604ms","start":"2026-04-14T05:11:33.693365Z","end":"2026-04-14T05:11:33.921414Z","steps":["trace[2063793417] 'agreement among raft nodes before linearized reading' (duration: 227.659653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:33.921149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.524219ms","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-04-14T05:11:33.921546Z","caller":"traceutil/trace.go:171","msg":"trace[2014207320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24681; }","duration":"227.945192ms","start":"2026-04-14T05:11:33.693587Z","end":"2026-04-14T05:11:33.921532Z","steps":["trace[2014207320] 'agreement among raft nodes before linearized reading' (duration: 227.476308ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:33.921902Z","caller":"traceutil/trace.go:171","msg":"trace[1886455262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24681; }","duration":"228.394213ms","start":"2026-04-14T05:11:33.692908Z","end":"2026-04-14T05:11:33.921303Z","steps":["trace[1886455262] 'agreement among raft nodes before linearized reading' (duration: 228.082355ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:41.775093Z","caller":"traceutil/trace.go:171","msg":"trace[2119689072] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"347.740832ms","start":"2026-04-14T05:11:41.427318Z","end":"2026-04-14T05:11:41.775059Z","steps":["trace[2119689072] 'process raft request' (duration: 347.592767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:41.775212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.427297Z","time spent":"347.865015ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:42.100969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.850288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:11:42.101085Z","caller":"traceutil/trace.go:171","msg":"trace[1648782919] linearizableReadLoop","detail":"{readStateIndex:25540; appliedIndex:25539; }","duration":"313.408511ms","start":"2026-04-14T05:11:41.787655Z","end":"2026-04-14T05:11:42.101064Z","steps":["trace[1648782919] 'read index received' (duration: 49.366878ms)","trace[1648782919] 'applied index is now lower than readState.Index' (duration: 264.040063ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:42.101184Z","caller":"traceutil/trace.go:171","msg":"trace[250852815] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"490.781961ms","start":"2026-04-14T05:11:41.610381Z","end":"2026-04-14T05:11:42.101163Z","steps":["trace[250852815] 'process raft request' (duration: 226.653776ms)","trace[250852815] 'compare' (duration: 263.741384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:42.101228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.551844ms","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":"warn","ts":"2026-04-14T05:11:42.101274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.610364Z","time spent":"490.863952ms","remote":"127.0.0.1:56728","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-04-14T05:11:42.101308Z","caller":"traceutil/trace.go:171","msg":"trace[820940282] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24737; }","duration":"313.672768ms","start":"2026-04-14T05:11:41.787616Z","end":"2026-04-14T05:11:42.101289Z","steps":["trace[820940282] 'agreement among raft nodes before linearized reading' (duration: 313.542864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.10136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.787602Z","time spent":"313.74701ms","remote":"127.0.0.1:49650","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/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"info","ts":"2026-04-14T05:11:42.157962Z","caller":"traceutil/trace.go:171","msg":"trace[1532223189] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"251.976491ms","start":"2026-04-14T05:11:41.905937Z","end":"2026-04-14T05:11:42.157914Z","steps":["trace[1532223189] 'process raft request' (duration: 251.846168ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:42.466356Z","caller":"traceutil/trace.go:171","msg":"trace[373342439] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"559.047682ms","start":"2026-04-14T05:11:41.907282Z","end":"2026-04-14T05:11:42.46633Z","steps":["trace[373342439] 'process raft request' (duration: 558.844527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.466549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.907264Z","time spent":"559.198047ms","remote":"127.0.0.1:56728","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-04-14T05:11:42.466718Z","caller":"traceutil/trace.go:171","msg":"trace[1336256745] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"557.508759ms","start":"2026-04-14T05:11:41.909186Z","end":"2026-04-14T05:11:42.466695Z","steps":["trace[1336256745] 'process raft request' (duration: 557.097947ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:42.466785Z","caller":"traceutil/trace.go:171","msg":"trace[1404474029] linearizableReadLoop","detail":"{readStateIndex:25545; appliedIndex:25540; }","duration":"365.633909ms","start":"2026-04-14T05:11:42.101119Z","end":"2026-04-14T05:11:42.466753Z","steps":["trace[1404474029] 'read index received' (duration: 56.734247ms)","trace[1404474029] 'applied index is now lower than readState.Index' (duration: 308.898572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:42.466849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.909181Z","time spent":"557.591842ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:42.466956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.293218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-04-14T05:11:42.467042Z","caller":"traceutil/trace.go:171","msg":"trace[1344868165] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24742; }","duration":"442.417732ms","start":"2026-04-14T05:11:42.024603Z","end":"2026-04-14T05:11:42.467021Z","steps":["trace[1344868165] 'agreement among raft nodes before linearized reading' (duration: 442.152554ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:42.46706Z","caller":"traceutil/trace.go:171","msg":"trace[1762241819] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"553.67341ms","start":"2026-04-14T05:11:41.913378Z","end":"2026-04-14T05:11:42.467051Z","steps":["trace[1762241819] 'process raft request' (duration: 553.322131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.467055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.009335ms","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-04-14T05:11:42.467025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.001927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:11:42.467096Z","caller":"traceutil/trace.go:171","msg":"trace[1464132862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24742; }","duration":"492.099388ms","start":"2026-04-14T05:11:41.974983Z","end":"2026-04-14T05:11:42.467082Z","steps":["trace[1464132862] 'agreement among raft nodes before linearized reading' (duration: 491.929774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.46712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.086239ms","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-04-14T05:11:42.467138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.913357Z","time spent":"553.726432ms","remote":"127.0.0.1:56728","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-04-14T05:11:42.467144Z","caller":"traceutil/trace.go:171","msg":"trace[1878972752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24742; }","duration":"491.170601ms","start":"2026-04-14T05:11:41.975947Z","end":"2026-04-14T05:11:42.467117Z","steps":["trace[1878972752] 'agreement among raft nodes before linearized reading' (duration: 490.857733ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:42.467162Z","caller":"traceutil/trace.go:171","msg":"trace[1862042199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24742; }","duration":"491.1576ms","start":"2026-04-14T05:11:41.975993Z","end":"2026-04-14T05:11:42.467151Z","steps":["trace[1862042199] 'agreement among raft nodes before linearized reading' (duration: 491.031217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.467168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.974957Z","time spent":"492.203231ms","remote":"127.0.0.1:56728","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":"info","ts":"2026-04-14T05:11:42.467179Z","caller":"traceutil/trace.go:171","msg":"trace[1160958621] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"554.863884ms","start":"2026-04-14T05:11:41.912287Z","end":"2026-04-14T05:11:42.467151Z","steps":["trace[1160958621] 'process raft request' (duration: 554.291548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.467187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.877278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-14T05:11:42.467089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.024586Z","time spent":"442.494153ms","remote":"127.0.0.1:56636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1878,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-14T05:11:42.467199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.975982Z","time spent":"491.208302ms","remote":"127.0.0.1:56728","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-04-14T05:11:42.467179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.702247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T05:11:42.46723Z","caller":"traceutil/trace.go:171","msg":"trace[1429872441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24742; }","duration":"381.95258ms","start":"2026-04-14T05:11:42.085269Z","end":"2026-04-14T05:11:42.467221Z","steps":["trace[1429872441] 'agreement among raft nodes before linearized reading' (duration: 381.843387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.467257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.912263Z","time spent":"554.955366ms","remote":"127.0.0.1:56728","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-04-14T05:11:42.467264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.085248Z","time spent":"382.010551ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T05:11:42.467274Z","caller":"traceutil/trace.go:171","msg":"trace[394868380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24742; }","duration":"491.850661ms","start":"2026-04-14T05:11:41.975403Z","end":"2026-04-14T05:11:42.467254Z","steps":["trace[394868380] 'agreement among raft nodes before linearized reading' (duration: 491.477341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.466991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.309355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-14T05:11:42.467326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.975384Z","time spent":"491.933873ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T05:11:42.467339Z","caller":"traceutil/trace.go:171","msg":"trace[1180051284] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:24742; }","duration":"226.715877ms","start":"2026-04-14T05:11:42.240615Z","end":"2026-04-14T05:11:42.46733Z","steps":["trace[1180051284] 'agreement among raft nodes before linearized reading' (duration: 226.321696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:42.467201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:41.975927Z","time spent":"491.263343ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T05:11:42.957238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.439952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:11:42.957331Z","caller":"traceutil/trace.go:171","msg":"trace[89070443] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"486.572662ms","start":"2026-04-14T05:11:42.470741Z","end":"2026-04-14T05:11:42.957313Z","steps":["trace[89070443] 'process raft request' (duration: 238.975875ms)","trace[89070443] 'compare' (duration: 247.086042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:42.957384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.470725Z","time spent":"486.630793ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:43.200395Z","caller":"traceutil/trace.go:171","msg":"trace[761759226] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"728.166669ms","start":"2026-04-14T05:11:42.472198Z","end":"2026-04-14T05:11:43.200365Z","steps":["trace[761759226] 'process raft request' (duration: 727.881811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:43.200541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.472185Z","time spent":"728.280342ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:43.200558Z","caller":"traceutil/trace.go:171","msg":"trace[987876796] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"728.633872ms","start":"2026-04-14T05:11:42.471913Z","end":"2026-04-14T05:11:43.200547Z","steps":["trace[987876796] 'process raft request' (duration: 728.115668ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:43.200571Z","caller":"traceutil/trace.go:171","msg":"trace[1618975480] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"729.503317ms","start":"2026-04-14T05:11:42.471035Z","end":"2026-04-14T05:11:43.200538Z","steps":["trace[1618975480] 'process raft request' (duration: 662.571662ms)","trace[1618975480] 'compare' (duration: 66.289137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:43.200651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.471882Z","time spent":"728.708684ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:43.200654Z","caller":"traceutil/trace.go:171","msg":"trace[1582159165] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"661.473851ms","start":"2026-04-14T05:11:42.539151Z","end":"2026-04-14T05:11:43.200625Z","steps":["trace[1582159165] 'process raft request' (duration: 660.996788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:43.200686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.47102Z","time spent":"729.6078ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:11:43.20074Z","caller":"traceutil/trace.go:171","msg":"trace[1824587346] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"728.458747ms","start":"2026-04-14T05:11:42.472249Z","end":"2026-04-14T05:11:43.200708Z","steps":["trace[1824587346] 'process raft request' (duration: 727.85515ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:43.200812Z","caller":"traceutil/trace.go:171","msg":"trace[579034911] linearizableReadLoop","detail":"{readStateIndex:25551; appliedIndex:25545; }","duration":"591.50317ms","start":"2026-04-14T05:11:42.6093Z","end":"2026-04-14T05:11:43.200803Z","steps":["trace[579034911] 'read index received' (duration: 100.693569ms)","trace[579034911] 'applied index is now lower than readState.Index' (duration: 490.808871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:43.200801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.539121Z","time spent":"661.596445ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:43.20089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.244622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T05:11:43.20089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.591593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T05:11:43.200897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.472244Z","time spent":"728.566131ms","remote":"127.0.0.1:56728","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-04-14T05:11:43.200914Z","caller":"traceutil/trace.go:171","msg":"trace[1932973592] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24749; }","duration":"286.307863ms","start":"2026-04-14T05:11:42.914598Z","end":"2026-04-14T05:11:43.200906Z","steps":["trace[1932973592] 'agreement among raft nodes before linearized reading' (duration: 286.269962ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:43.201033Z","caller":"traceutil/trace.go:171","msg":"trace[802414637] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:24749; }","duration":"591.632005ms","start":"2026-04-14T05:11:42.609285Z","end":"2026-04-14T05:11:43.200917Z","steps":["trace[802414637] 'agreement among raft nodes before linearized reading' (duration: 591.559832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:43.201084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.609231Z","time spent":"591.84099ms","remote":"127.0.0.1:49498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"info","ts":"2026-04-14T05:11:43.200241Z","caller":"traceutil/trace.go:171","msg":"trace[690975077] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"339.718986ms","start":"2026-04-14T05:11:42.860498Z","end":"2026-04-14T05:11:43.200217Z","steps":["trace[690975077] 'process raft request' (duration: 339.687175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:43.201203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:11:42.860473Z","time spent":"340.672952ms","remote":"127.0.0.1:56728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:11:43.570404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.809615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:11:43.57048Z","caller":"traceutil/trace.go:171","msg":"trace[1975475359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24749; }","duration":"217.937328ms","start":"2026-04-14T05:11:43.352525Z","end":"2026-04-14T05:11:43.570463Z","steps":["trace[1975475359] 'range keys from in-memory index tree' (duration: 217.729183ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:45.53929Z","caller":"traceutil/trace.go:171","msg":"trace[598192097] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"117.008662ms","start":"2026-04-14T05:11:45.422238Z","end":"2026-04-14T05:11:45.539246Z","steps":["trace[598192097] 'process raft request' (duration: 116.760335ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:47.129291Z","caller":"traceutil/trace.go:171","msg":"trace[1697406192] linearizableReadLoop","detail":"{readStateIndex:25639; appliedIndex:25638; }","duration":"145.598901ms","start":"2026-04-14T05:11:46.983667Z","end":"2026-04-14T05:11:47.129266Z","steps":["trace[1697406192] 'read index received' (duration: 145.427216ms)","trace[1697406192] 'applied index is now lower than readState.Index' (duration: 170.285µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:47.129399Z","caller":"traceutil/trace.go:171","msg":"trace[1017256514] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"156.456468ms","start":"2026-04-14T05:11:46.972914Z","end":"2026-04-14T05:11:47.129371Z","steps":["trace[1017256514] 'process raft request' (duration: 156.195281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:11:47.129548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.846878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-04-14T05:11:47.129623Z","caller":"traceutil/trace.go:171","msg":"trace[1861697563] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:24835; }","duration":"145.95141ms","start":"2026-04-14T05:11:46.983651Z","end":"2026-04-14T05:11:47.129602Z","steps":["trace[1861697563] 'agreement among raft nodes before linearized reading' (duration: 145.707234ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:11:52.422516Z","caller":"traceutil/trace.go:171","msg":"trace[553585339] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"189.825562ms","start":"2026-04-14T05:11:52.232657Z","end":"2026-04-14T05:11:52.422483Z","steps":["trace[553585339] 'process raft request' (duration: 116.241489ms)","trace[553585339] 'compare' (duration: 73.414939ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:54.380542Z","caller":"traceutil/trace.go:171","msg":"trace[124643868] linearizableReadLoop","detail":"{readStateIndex:25724; appliedIndex:25722; }","duration":"139.598621ms","start":"2026-04-14T05:11:54.240919Z","end":"2026-04-14T05:11:54.380517Z","steps":["trace[124643868] 'read index received' (duration: 8.967744ms)","trace[124643868] 'applied index is now lower than readState.Index' (duration: 130.629717ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:11:54.380696Z","caller":"traceutil/trace.go:171","msg":"trace[1662506427] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"155.542913ms","start":"2026-04-14T05:11:54.225135Z","end":"2026-04-14T05:11:54.380678Z","steps":["trace[1662506427] 'process raft request' (duration: 144.520431ms)","trace[1662506427] 'compare' (duration: 10.567959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:11:54.381325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.388383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-04-14T05:11:54.382123Z","caller":"traceutil/trace.go:171","msg":"trace[1848806197] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24918; }","duration":"140.492046ms","start":"2026-04-14T05:11:54.240898Z","end":"2026-04-14T05:11:54.38139Z","steps":["trace[1848806197] 'agreement among raft nodes before linearized reading' (duration: 140.205688ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:10.611721Z","caller":"traceutil/trace.go:171","msg":"trace[900423536] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"128.104476ms","start":"2026-04-14T05:12:10.483596Z","end":"2026-04-14T05:12:10.6117Z","steps":["trace[900423536] 'process raft request' (duration: 127.987722ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:11.005665Z","caller":"traceutil/trace.go:171","msg":"trace[1304074906] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"127.663383ms","start":"2026-04-14T05:12:10.877974Z","end":"2026-04-14T05:12:11.005637Z","steps":["trace[1304074906] 'process raft request' (duration: 127.494198ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:11.298765Z","caller":"traceutil/trace.go:171","msg":"trace[1250182282] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"283.269477ms","start":"2026-04-14T05:12:11.015468Z","end":"2026-04-14T05:12:11.298737Z","steps":["trace[1250182282] 'process raft request' (duration: 283.083502ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:11.315135Z","caller":"traceutil/trace.go:171","msg":"trace[1550021178] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"134.410725ms","start":"2026-04-14T05:12:11.180698Z","end":"2026-04-14T05:12:11.315109Z","steps":["trace[1550021178] 'process raft request' (duration: 134.193039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:11.557171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.596373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:12:11.557273Z","caller":"traceutil/trace.go:171","msg":"trace[1276702181] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"237.78952ms","start":"2026-04-14T05:12:11.319462Z","end":"2026-04-14T05:12:11.557252Z","steps":["trace[1276702181] 'process raft request' (duration: 52.035244ms)","trace[1276702181] 'compare' (duration: 185.500049ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:12:11.601501Z","caller":"traceutil/trace.go:171","msg":"trace[1187327637] linearizableReadLoop","detail":"{readStateIndex:25877; appliedIndex:25875; }","duration":"278.288316ms","start":"2026-04-14T05:12:11.323185Z","end":"2026-04-14T05:12:11.601473Z","steps":["trace[1187327637] 'read index received' (duration: 48.308368ms)","trace[1187327637] 'applied index is now lower than readState.Index' (duration: 229.979018ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:12:11.601577Z","caller":"traceutil/trace.go:171","msg":"trace[593319349] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"280.608191ms","start":"2026-04-14T05:12:11.320944Z","end":"2026-04-14T05:12:11.601552Z","steps":["trace[593319349] 'process raft request' (duration: 280.399095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:11.601775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.571524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-04-14T05:12:11.601821Z","caller":"traceutil/trace.go:171","msg":"trace[2133119381] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25067; }","duration":"278.647255ms","start":"2026-04-14T05:12:11.323163Z","end":"2026-04-14T05:12:11.60181Z","steps":["trace[2133119381] 'agreement among raft nodes before linearized reading' (duration: 278.45899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:11.601843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.033994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T05:12:11.601862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.81921ms","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-04-14T05:12:11.601929Z","caller":"traceutil/trace.go:171","msg":"trace[1273596703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25067; }","duration":"252.141776ms","start":"2026-04-14T05:12:11.349768Z","end":"2026-04-14T05:12:11.60191Z","steps":["trace[1273596703] 'agreement among raft nodes before linearized reading' (duration: 251.995382ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:11.601941Z","caller":"traceutil/trace.go:171","msg":"trace[936914917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25067; }","duration":"273.935313ms","start":"2026-04-14T05:12:11.327986Z","end":"2026-04-14T05:12:11.601921Z","steps":["trace[936914917] 'agreement among raft nodes before linearized reading' (duration: 273.721237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:11.601941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.500396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T05:12:11.60201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.939884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T05:12:11.602013Z","caller":"traceutil/trace.go:171","msg":"trace[2097691509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25067; }","duration":"270.602788ms","start":"2026-04-14T05:12:11.331391Z","end":"2026-04-14T05:12:11.601994Z","steps":["trace[2097691509] 'agreement among raft nodes before linearized reading' (duration: 270.385022ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:11.602041Z","caller":"traceutil/trace.go:171","msg":"trace[637672057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25067; }","duration":"273.995165ms","start":"2026-04-14T05:12:11.328038Z","end":"2026-04-14T05:12:11.602033Z","steps":["trace[637672057] 'agreement among raft nodes before linearized reading' (duration: 273.856061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:11.602138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.956305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-14T05:12:11.602225Z","caller":"traceutil/trace.go:171","msg":"trace[2042687506] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25067; }","duration":"231.096459ms","start":"2026-04-14T05:12:11.371106Z","end":"2026-04-14T05:12:11.602203Z","steps":["trace[2042687506] 'agreement among raft nodes before linearized reading' (duration: 230.793381ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:15.434874Z","caller":"traceutil/trace.go:171","msg":"trace[999844380] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"100.801263ms","start":"2026-04-14T05:12:15.334047Z","end":"2026-04-14T05:12:15.434848Z","steps":["trace[999844380] 'process raft request' (duration: 100.449513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:15.655242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.978548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-14T05:12:15.656266Z","caller":"traceutil/trace.go:171","msg":"trace[1818829237] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25094; }","duration":"102.039848ms","start":"2026-04-14T05:12:15.554198Z","end":"2026-04-14T05:12:15.656238Z","steps":["trace[1818829237] 'range keys from in-memory index tree' (duration: 100.815903ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:15.876115Z","caller":"traceutil/trace.go:171","msg":"trace[1179565592] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"213.78331ms","start":"2026-04-14T05:12:15.662303Z","end":"2026-04-14T05:12:15.876086Z","steps":["trace[1179565592] 'process raft request' (duration: 213.741709ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:15.876772Z","caller":"traceutil/trace.go:171","msg":"trace[274030495] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"216.546478ms","start":"2026-04-14T05:12:15.660211Z","end":"2026-04-14T05:12:15.876757Z","steps":["trace[274030495] 'process raft request' (duration: 215.727305ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:15.878514Z","caller":"traceutil/trace.go:171","msg":"trace[542575816] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"218.524155ms","start":"2026-04-14T05:12:15.659975Z","end":"2026-04-14T05:12:15.878499Z","steps":["trace[542575816] 'process raft request' (duration: 215.796378ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:15.878901Z","caller":"traceutil/trace.go:171","msg":"trace[262938652] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"217.742943ms","start":"2026-04-14T05:12:15.661062Z","end":"2026-04-14T05:12:15.878805Z","steps":["trace[262938652] 'process raft request' (duration: 214.919333ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:15.879017Z","caller":"traceutil/trace.go:171","msg":"trace[305720947] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"217.429944ms","start":"2026-04-14T05:12:15.661571Z","end":"2026-04-14T05:12:15.879001Z","steps":["trace[305720947] 'process raft request' (duration: 214.441789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:16.05994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.98633ms","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-04-14T05:12:16.060036Z","caller":"traceutil/trace.go:171","msg":"trace[1670547565] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25100; }","duration":"129.139236ms","start":"2026-04-14T05:12:15.930873Z","end":"2026-04-14T05:12:16.060012Z","steps":["trace[1670547565] 'count revisions from in-memory index tree' (duration: 128.836957ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:16.79556Z","caller":"traceutil/trace.go:171","msg":"trace[1403191522] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"140.731563ms","start":"2026-04-14T05:12:16.654799Z","end":"2026-04-14T05:12:16.79553Z","steps":["trace[1403191522] 'process raft request' (duration: 62.033116ms)","trace[1403191522] 'compare' (duration: 78.545193ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:12:16.803554Z","caller":"traceutil/trace.go:171","msg":"trace[1371077553] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"148.244236ms","start":"2026-04-14T05:12:16.655298Z","end":"2026-04-14T05:12:16.803542Z","steps":["trace[1371077553] 'process raft request' (duration: 148.150924ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:16.803716Z","caller":"traceutil/trace.go:171","msg":"trace[1959510184] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"143.311436ms","start":"2026-04-14T05:12:16.660398Z","end":"2026-04-14T05:12:16.803709Z","steps":["trace[1959510184] 'process raft request' (duration: 143.118251ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:16.804287Z","caller":"traceutil/trace.go:171","msg":"trace[1957884476] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"143.348937ms","start":"2026-04-14T05:12:16.660916Z","end":"2026-04-14T05:12:16.804265Z","steps":["trace[1957884476] 'process raft request' (duration: 142.75437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:17.562574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.644438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:12:17.562707Z","caller":"traceutil/trace.go:171","msg":"trace[721799003] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25919; }","duration":"103.831568ms","start":"2026-04-14T05:12:17.458863Z","end":"2026-04-14T05:12:17.562695Z","steps":["trace[721799003] 'read index received' (duration: 60.992356ms)","trace[721799003] 'applied index is now lower than readState.Index' (duration: 42.838172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:12:17.562782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.921661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T05:12:17.5628Z","caller":"traceutil/trace.go:171","msg":"trace[1655953622] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25110; }","duration":"103.993143ms","start":"2026-04-14T05:12:17.458801Z","end":"2026-04-14T05:12:17.562794Z","steps":["trace[1655953622] 'agreement among raft nodes before linearized reading' (duration: 103.927881ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:17.562965Z","caller":"traceutil/trace.go:171","msg":"trace[818170901] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"122.9152ms","start":"2026-04-14T05:12:17.440042Z","end":"2026-04-14T05:12:17.562958Z","steps":["trace[818170901] 'process raft request' (duration: 122.612301ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:17.5631Z","caller":"traceutil/trace.go:171","msg":"trace[113158735] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"150.442599ms","start":"2026-04-14T05:12:17.412623Z","end":"2026-04-14T05:12:17.563066Z","steps":["trace[113158735] 'process raft request' (duration: 25.228314ms)","trace[113158735] 'compare' (duration: 124.440303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:12:17.983627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.577507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-14T05:12:17.983687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.619773ms","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-04-14T05:12:17.983762Z","caller":"traceutil/trace.go:171","msg":"trace[13659588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25111; }","duration":"103.793768ms","start":"2026-04-14T05:12:17.87995Z","end":"2026-04-14T05:12:17.983744Z","steps":["trace[13659588] 'range keys from in-memory index tree' (duration: 103.247323ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:17.98376Z","caller":"traceutil/trace.go:171","msg":"trace[1987171620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25111; }","duration":"100.786773ms","start":"2026-04-14T05:12:17.882937Z","end":"2026-04-14T05:12:17.983724Z","steps":["trace[1987171620] 'range keys from in-memory index tree' (duration: 100.313199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:17.98381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.499033ms","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-04-14T05:12:17.983871Z","caller":"traceutil/trace.go:171","msg":"trace[190884462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25111; }","duration":"101.588305ms","start":"2026-04-14T05:12:17.882267Z","end":"2026-04-14T05:12:17.983855Z","steps":["trace[190884462] 'range keys from in-memory index tree' (duration: 101.119272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:17.983996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.35542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:12:17.984045Z","caller":"traceutil/trace.go:171","msg":"trace[1972923420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25111; }","duration":"100.432543ms","start":"2026-04-14T05:12:17.883604Z","end":"2026-04-14T05:12:17.984037Z","steps":["trace[1972923420] 'range keys from in-memory index tree' (duration: 99.67219ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:20.331833Z","caller":"traceutil/trace.go:171","msg":"trace[1197264543] linearizableReadLoop","detail":"{readStateIndex:25941; appliedIndex:25940; }","duration":"119.038819ms","start":"2026-04-14T05:12:20.21277Z","end":"2026-04-14T05:12:20.331808Z","steps":["trace[1197264543] 'read index received' (duration: 118.922325ms)","trace[1197264543] 'applied index is now lower than readState.Index' (duration: 114.854µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:12:20.331974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.178292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:12:20.332008Z","caller":"traceutil/trace.go:171","msg":"trace[1085492780] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25129; }","duration":"119.270225ms","start":"2026-04-14T05:12:20.212726Z","end":"2026-04-14T05:12:20.331997Z","steps":["trace[1085492780] 'agreement among raft nodes before linearized reading' (duration: 119.184953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:23.239282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.408067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T05:12:23.2394Z","caller":"traceutil/trace.go:171","msg":"trace[847982682] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25150; }","duration":"303.581252ms","start":"2026-04-14T05:12:22.935794Z","end":"2026-04-14T05:12:23.239376Z","steps":["trace[847982682] 'count revisions from in-memory index tree' (duration: 303.332335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:23.239465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:12:22.935777Z","time spent":"303.668075ms","remote":"127.0.0.1:56714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":32,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-14T05:12:23.239574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.454155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-7c5fb5b747-hbslw.18a62114c913f8c9\" ","response":"range_response_count:1 size:890"} {"level":"info","ts":"2026-04-14T05:12:23.239602Z","caller":"traceutil/trace.go:171","msg":"trace[957352059] range","detail":"{range_begin:/registry/events/openstack/magnum-api-7c5fb5b747-hbslw.18a62114c913f8c9; range_end:; response_count:1; response_revision:25150; }","duration":"311.513707ms","start":"2026-04-14T05:12:22.928078Z","end":"2026-04-14T05:12:23.239591Z","steps":["trace[957352059] 'range keys from in-memory index tree' (duration: 311.345092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:23.239622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:12:22.928059Z","time spent":"311.557417ms","remote":"127.0.0.1:56536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":914,"request content":"key:\"/registry/events/openstack/magnum-api-7c5fb5b747-hbslw.18a62114c913f8c9\" "} {"level":"warn","ts":"2026-04-14T05:12:23.239339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.526534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-04-14T05:12:23.239734Z","caller":"traceutil/trace.go:171","msg":"trace[1759478820] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25150; }","duration":"224.931505ms","start":"2026-04-14T05:12:23.014752Z","end":"2026-04-14T05:12:23.239684Z","steps":["trace[1759478820] 'range keys from in-memory index tree' (duration: 224.351ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:28.00781Z","caller":"traceutil/trace.go:171","msg":"trace[1232914350] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"119.066579ms","start":"2026-04-14T05:12:27.888719Z","end":"2026-04-14T05:12:28.007785Z","steps":["trace[1232914350] 'process raft request' (duration: 118.906584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:32.427508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.28786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:12:32.427588Z","caller":"traceutil/trace.go:171","msg":"trace[1727015624] linearizableReadLoop","detail":"{readStateIndex:26037; appliedIndex:26036; }","duration":"255.532543ms","start":"2026-04-14T05:12:32.172041Z","end":"2026-04-14T05:12:32.427574Z","steps":["trace[1727015624] 'read index received' (duration: 132.089389ms)","trace[1727015624] 'applied index is now lower than readState.Index' (duration: 123.442303ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:12:32.427646Z","caller":"traceutil/trace.go:171","msg":"trace[1985170658] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"256.355165ms","start":"2026-04-14T05:12:32.171283Z","end":"2026-04-14T05:12:32.427638Z","steps":["trace[1985170658] 'process raft request' (duration: 132.86961ms)","trace[1985170658] 'compare' (duration: 123.155816ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:12:32.428157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.687221ms","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-04-14T05:12:32.428238Z","caller":"traceutil/trace.go:171","msg":"trace[1581030317] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25222; }","duration":"229.799634ms","start":"2026-04-14T05:12:32.198418Z","end":"2026-04-14T05:12:32.428217Z","steps":["trace[1581030317] 'agreement among raft nodes before linearized reading' (duration: 229.65648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:32.428233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.17431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-14T05:12:32.42832Z","caller":"traceutil/trace.go:171","msg":"trace[1954468324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25222; }","duration":"256.276803ms","start":"2026-04-14T05:12:32.172023Z","end":"2026-04-14T05:12:32.428299Z","steps":["trace[1954468324] 'agreement among raft nodes before linearized reading' (duration: 256.039436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:32.42831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.17745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T05:12:32.4284Z","caller":"traceutil/trace.go:171","msg":"trace[1489795639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25222; }","duration":"256.295673ms","start":"2026-04-14T05:12:32.172081Z","end":"2026-04-14T05:12:32.428377Z","steps":["trace[1489795639] 'agreement among raft nodes before linearized reading' (duration: 256.006975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:12:32.429006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.925452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-14T05:12:32.429064Z","caller":"traceutil/trace.go:171","msg":"trace[1591720404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25222; }","duration":"257.018325ms","start":"2026-04-14T05:12:32.172032Z","end":"2026-04-14T05:12:32.42905Z","steps":["trace[1591720404] 'agreement among raft nodes before linearized reading' (duration: 255.707717ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:32.966989Z","caller":"traceutil/trace.go:171","msg":"trace[412046644] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"114.687706ms","start":"2026-04-14T05:12:32.852205Z","end":"2026-04-14T05:12:32.966893Z","steps":["trace[412046644] 'process raft request' (duration: 114.594263ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:32.967019Z","caller":"traceutil/trace.go:171","msg":"trace[583978591] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"115.452547ms","start":"2026-04-14T05:12:32.851525Z","end":"2026-04-14T05:12:32.966978Z","steps":["trace[583978591] 'process raft request' (duration: 110.510337ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:12:32.96706Z","caller":"traceutil/trace.go:171","msg":"trace[1354326248] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"106.150205ms","start":"2026-04-14T05:12:32.860873Z","end":"2026-04-14T05:12:32.967024Z","steps":["trace[1354326248] 'process raft request' (duration: 105.98396ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:09.655559Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006998.wal"} {"level":"info","ts":"2026-04-14T05:14:17.059212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23357} {"level":"info","ts":"2026-04-14T05:14:17.194763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23357,"took":"131.646886ms","hash":3262567702,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":36827136,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-14T05:14:17.194891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3262567702,"revision":23357,"compact-revision":20124} {"level":"warn","ts":"2026-04-14T05:15:01.317029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.350289ms","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-04-14T05:15:01.317118Z","caller":"traceutil/trace.go:171","msg":"trace[236774070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26714; }","duration":"130.508915ms","start":"2026-04-14T05:15:01.186593Z","end":"2026-04-14T05:15:01.317102Z","steps":["trace[236774070] 'range keys from in-memory index tree' (duration: 130.181165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:37.761143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.955705ms","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-04-14T05:15:37.761212Z","caller":"traceutil/trace.go:171","msg":"trace[974383635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27147; }","duration":"179.065728ms","start":"2026-04-14T05:15:37.582132Z","end":"2026-04-14T05:15:37.761198Z","steps":["trace[974383635] 'range keys from in-memory index tree' (duration: 178.822641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:37.761322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.143193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T05:15:37.761362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.714324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T05:15:37.761403Z","caller":"traceutil/trace.go:171","msg":"trace[2036930406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27147; }","duration":"177.273797ms","start":"2026-04-14T05:15:37.58411Z","end":"2026-04-14T05:15:37.761384Z","steps":["trace[2036930406] 'range keys from in-memory index tree' (duration: 176.964588ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:37.761425Z","caller":"traceutil/trace.go:171","msg":"trace[817821369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27147; }","duration":"174.810077ms","start":"2026-04-14T05:15:37.586599Z","end":"2026-04-14T05:15:37.761409Z","steps":["trace[817821369] 'range keys from in-memory index tree' (duration: 174.583271ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:37.761483Z","caller":"traceutil/trace.go:171","msg":"trace[1635367021] transaction","detail":"{read_only:false; response_revision:27148; number_of_response:1; }","duration":"119.064059ms","start":"2026-04-14T05:15:37.642385Z","end":"2026-04-14T05:15:37.761449Z","steps":["trace[1635367021] 'process raft request' (duration: 108.176261ms)","trace[1635367021] 'compare' (duration: 10.427216ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:44.607783Z","caller":"traceutil/trace.go:171","msg":"trace[1524910628] transaction","detail":"{read_only:false; response_revision:27206; number_of_response:1; }","duration":"109.514249ms","start":"2026-04-14T05:15:44.498244Z","end":"2026-04-14T05:15:44.607759Z","steps":["trace[1524910628] 'process raft request' (duration: 109.363325ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:19:17.066264Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26286} {"level":"info","ts":"2026-04-14T05:19:17.206758Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26286,"took":"137.238785ms","hash":103443128,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":33722368,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-14T05:19:17.206864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":103443128,"revision":26286,"compact-revision":23357} {"level":"info","ts":"2026-04-14T05:19:43.023459Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T05:19:43.027999Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-14T05:19:43.028105Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-14T05:24:17.071952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28899} {"level":"info","ts":"2026-04-14T05:24:17.205078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28899,"took":"129.561675ms","hash":3283297234,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":30277632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T05:24:17.205163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3283297234,"revision":28899,"compact-revision":26286} {"level":"info","ts":"2026-04-14T05:29:17.078814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31121} {"level":"info","ts":"2026-04-14T05:29:17.196923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31121,"took":"112.830744ms","hash":4256371051,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":31408128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-14T05:29:17.196989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4256371051,"revision":31121,"compact-revision":28899} {"level":"info","ts":"2026-04-14T05:34:17.085919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33428} {"level":"info","ts":"2026-04-14T05:34:17.234117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33428,"took":"143.637595ms","hash":3687491569,"current-db-size-bytes":59703296,"current-db-size":"60 MB","current-db-size-in-use-bytes":32616448,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-14T05:34:17.234197Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3687491569,"revision":33428,"compact-revision":31121} {"level":"info","ts":"2026-04-14T05:39:17.092842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35696} {"level":"info","ts":"2026-04-14T05:39:17.239847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35696,"took":"142.75649ms","hash":3162093281,"current-db-size-bytes":59703296,"current-db-size":"60 MB","current-db-size-in-use-bytes":31576064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T05:39:17.239926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3162093281,"revision":35696,"compact-revision":33428} {"level":"info","ts":"2026-04-14T05:41:02.986939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T05:41:02.992973Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-14T05:41:02.993064Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}