{"level":"warn","ts":"2026-04-16T16:20:46.389116Z","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-16T16:20:46.389213Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-16T16:20:46.389286Z","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-16T16:20:46.389297Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-16T16:20:46.389314Z","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-16T16:20:46.389929Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-04-16T16:20:46.390002Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-16T16:20:46.394203Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.936268ms"} {"level":"info","ts":"2026-04-16T16:20:46.401256Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-04-16T16:20:46.401335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:20:46.401359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-04-16T16:20:46.401375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:20:46.401389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-04-16T16:20:46.401449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-04-16T16:20:46.407949Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:20:46.409877Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:20:46.41126Z","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-16T16:20:46.41454Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:20:46.41496Z","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-16T16:20:46.4151Z","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-16T16:20:46.414896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:20:46.415126Z","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-16T16:20:46.419801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-04-16T16:20:46.420249Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-16T16:20:46.421971Z","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-16T16:20:46.422078Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-16T16:20:46.422152Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-16T16:20:46.422387Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:20:46.422461Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:20:46.502658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:20:46.502722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:20:46.50275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-04-16T16:20:46.502771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:20:46.50278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-16T16:20:46.502793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-04-16T16:20:46.502808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-16T16:20:46.503669Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:20:46.504697Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:20:46.50502Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:20:46.50516Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:20:46.50527Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:20:46.505304Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:20:46.505371Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:20:46.505387Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T16:20:46.505395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:20:46.50692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:20:46.507937Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-04-16T16:21:06.731563Z","caller":"traceutil/trace.go:171","msg":"trace[272939437] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:466; }","duration":"173.093805ms","start":"2026-04-16T16:21:06.55844Z","end":"2026-04-16T16:21:06.731534Z","steps":["trace[272939437] 'read index received' (duration: 173.048734ms)","trace[272939437] 'applied index is now lower than readState.Index' (duration: 43.641µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:21:06.731664Z","caller":"traceutil/trace.go:171","msg":"trace[1444358536] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"177.995312ms","start":"2026-04-16T16:21:06.553631Z","end":"2026-04-16T16:21:06.731627Z","steps":["trace[1444358536] 'process raft request' (duration: 177.766468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:21:06.731743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.2826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:21:06.731839Z","caller":"traceutil/trace.go:171","msg":"trace[901937428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:447; }","duration":"173.423502ms","start":"2026-04-16T16:21:06.558402Z","end":"2026-04-16T16:21:06.731826Z","steps":["trace[901937428] 'agreement among raft nodes before linearized reading' (duration: 173.275809ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.045829Z","caller":"traceutil/trace.go:171","msg":"trace[1310250891] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"207.526451ms","start":"2026-04-16T16:21:15.838236Z","end":"2026-04-16T16:21:16.045762Z","steps":["trace[1310250891] 'process raft request' (duration: 207.268095ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.045965Z","caller":"traceutil/trace.go:171","msg":"trace[1539079233] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"199.356542ms","start":"2026-04-16T16:21:15.846581Z","end":"2026-04-16T16:21:16.045938Z","steps":["trace[1539079233] 'process raft request' (duration: 199.148898ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.046097Z","caller":"traceutil/trace.go:171","msg":"trace[657976030] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"198.142408ms","start":"2026-04-16T16:21:15.847942Z","end":"2026-04-16T16:21:16.046084Z","steps":["trace[657976030] 'process raft request' (duration: 197.825612ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.046121Z","caller":"traceutil/trace.go:171","msg":"trace[688214742] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"197.036447ms","start":"2026-04-16T16:21:15.849038Z","end":"2026-04-16T16:21:16.046074Z","steps":["trace[688214742] 'process raft request' (duration: 196.809362ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.046299Z","caller":"traceutil/trace.go:171","msg":"trace[1767175761] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"196.268931ms","start":"2026-04-16T16:21:15.850017Z","end":"2026-04-16T16:21:16.046286Z","steps":["trace[1767175761] 'process raft request' (duration: 195.886283ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.046328Z","caller":"traceutil/trace.go:171","msg":"trace[451449703] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"190.092841ms","start":"2026-04-16T16:21:15.856194Z","end":"2026-04-16T16:21:16.046287Z","steps":["trace[451449703] 'process raft request' (duration: 189.811445ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.046369Z","caller":"traceutil/trace.go:171","msg":"trace[360473344] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:531; }","duration":"108.481107ms","start":"2026-04-16T16:21:15.937869Z","end":"2026-04-16T16:21:16.04635Z","steps":["trace[360473344] 'read index received' (duration: 107.747532ms)","trace[360473344] 'applied index is now lower than readState.Index' (duration: 732.715µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:21:16.046392Z","caller":"traceutil/trace.go:171","msg":"trace[1698134095] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"159.074479ms","start":"2026-04-16T16:21:15.887309Z","end":"2026-04-16T16:21:16.046383Z","steps":["trace[1698134095] 'process raft request' (duration: 158.943316ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.046396Z","caller":"traceutil/trace.go:171","msg":"trace[296938407] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"179.107198ms","start":"2026-04-16T16:21:15.867271Z","end":"2026-04-16T16:21:16.046378Z","steps":["trace[296938407] 'process raft request' (duration: 178.878333ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.046225Z","caller":"traceutil/trace.go:171","msg":"trace[31081599] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"206.962009ms","start":"2026-04-16T16:21:15.839238Z","end":"2026-04-16T16:21:16.0462Z","steps":["trace[31081599] 'process raft request' (duration: 206.434538ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.045954Z","caller":"traceutil/trace.go:171","msg":"trace[1837642832] transaction","detail":"{read_only:false; number_of_response:0; response_revision:509; }","duration":"207.629713ms","start":"2026-04-16T16:21:15.83828Z","end":"2026-04-16T16:21:16.04591Z","steps":["trace[1837642832] 'process raft request' (duration: 207.351196ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:16.046266Z","caller":"traceutil/trace.go:171","msg":"trace[469048328] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"195.984395ms","start":"2026-04-16T16:21:15.850274Z","end":"2026-04-16T16:21:16.046258Z","steps":["trace[469048328] 'process raft request' (duration: 195.661509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:21:16.046529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.64986ms","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-16T16:21:16.046891Z","caller":"traceutil/trace.go:171","msg":"trace[1679805007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"109.040587ms","start":"2026-04-16T16:21:15.937824Z","end":"2026-04-16T16:21:16.046865Z","steps":["trace[1679805007] 'agreement among raft nodes before linearized reading' (duration: 108.609529ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:22.066703Z","caller":"traceutil/trace.go:171","msg":"trace[595992589] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"201.774698ms","start":"2026-04-16T16:21:21.864887Z","end":"2026-04-16T16:21:22.066661Z","steps":["trace[595992589] 'process raft request' (duration: 201.531513ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:22.277019Z","caller":"traceutil/trace.go:171","msg":"trace[629615454] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"195.471576ms","start":"2026-04-16T16:21:22.081479Z","end":"2026-04-16T16:21:22.276951Z","steps":["trace[629615454] 'read index received' (duration: 183.630246ms)","trace[629615454] 'applied index is now lower than readState.Index' (duration: 11.84024ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:21:22.277038Z","caller":"traceutil/trace.go:171","msg":"trace[1184078496] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"312.568021ms","start":"2026-04-16T16:21:21.964434Z","end":"2026-04-16T16:21:22.277002Z","steps":["trace[1184078496] 'process raft request' (duration: 300.558937ms)","trace[1184078496] 'compare' (duration: 11.851341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:21:22.27725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.760292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T16:21:22.277296Z","caller":"traceutil/trace.go:171","msg":"trace[412131070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"195.864913ms","start":"2026-04-16T16:21:22.08142Z","end":"2026-04-16T16:21:22.277285Z","steps":["trace[412131070] 'agreement among raft nodes before linearized reading' (duration: 195.6942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:21:22.277692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:21:21.964404Z","time spent":"312.702703ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:21:22.316871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.193898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T16:21:22.316954Z","caller":"traceutil/trace.go:171","msg":"trace[694471708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"216.317641ms","start":"2026-04-16T16:21:22.100616Z","end":"2026-04-16T16:21:22.316934Z","steps":["trace[694471708] 'agreement among raft nodes before linearized reading' (duration: 216.090746ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:22.655115Z","caller":"traceutil/trace.go:171","msg":"trace[1029120359] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"309.247175ms","start":"2026-04-16T16:21:22.34584Z","end":"2026-04-16T16:21:22.655087Z","steps":["trace[1029120359] 'process raft request' (duration: 308.882768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:21:22.655266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:21:22.345807Z","time spent":"309.380388ms","remote":"127.0.0.1:46486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:21:22.830305Z","caller":"traceutil/trace.go:171","msg":"trace[689399200] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"451.681701ms","start":"2026-04-16T16:21:22.378598Z","end":"2026-04-16T16:21:22.83028Z","steps":["trace[689399200] 'process raft request' (duration: 439.665458ms)","trace[689399200] 'compare' (duration: 11.743728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:21:22.830441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:21:22.378574Z","time spent":"451.810404ms","remote":"127.0.0.1:46562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:21:38.09068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.697331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5bw49\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-16T16:21:38.09077Z","caller":"traceutil/trace.go:171","msg":"trace[1085324429] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5bw49; range_end:; response_count:1; response_revision:773; }","duration":"124.840334ms","start":"2026-04-16T16:21:37.965912Z","end":"2026-04-16T16:21:38.090753Z","steps":["trace[1085324429] 'range keys from in-memory index tree' (duration: 124.531828ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:21:38.243896Z","caller":"traceutil/trace.go:171","msg":"trace[590877847] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"145.589958ms","start":"2026-04-16T16:21:38.09827Z","end":"2026-04-16T16:21:38.24386Z","steps":["trace[590877847] 'process raft request' (duration: 145.368463ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:05.173387Z","caller":"traceutil/trace.go:171","msg":"trace[1777648982] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"124.60914ms","start":"2026-04-16T16:22:05.048754Z","end":"2026-04-16T16:22:05.173363Z","steps":["trace[1777648982] 'read index received' (duration: 48.754546ms)","trace[1777648982] 'applied index is now lower than readState.Index' (duration: 75.853034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:22:05.173777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.992637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:22:05.173897Z","caller":"traceutil/trace.go:171","msg":"trace[183983650] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1165; }","duration":"125.176651ms","start":"2026-04-16T16:22:05.0487Z","end":"2026-04-16T16:22:05.173877Z","steps":["trace[183983650] 'agreement among raft nodes before linearized reading' (duration: 124.839275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:05.173902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.020715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T16:22:05.173981Z","caller":"traceutil/trace.go:171","msg":"trace[1825158941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"113.127137ms","start":"2026-04-16T16:22:05.060836Z","end":"2026-04-16T16:22:05.173963Z","steps":["trace[1825158941] 'agreement among raft nodes before linearized reading' (duration: 112.839251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:05.174004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.246559ms","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-16T16:22:05.174052Z","caller":"traceutil/trace.go:171","msg":"trace[245721326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"114.35006ms","start":"2026-04-16T16:22:05.059688Z","end":"2026-04-16T16:22:05.174039Z","steps":["trace[245721326] 'agreement among raft nodes before linearized reading' (duration: 113.971023ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:51.15066Z","caller":"traceutil/trace.go:171","msg":"trace[1681387610] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"108.017594ms","start":"2026-04-16T16:22:51.042616Z","end":"2026-04-16T16:22:51.150633Z","steps":["trace[1681387610] 'process raft request' (duration: 107.79639ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:51.626344Z","caller":"traceutil/trace.go:171","msg":"trace[1469940979] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"255.636568ms","start":"2026-04-16T16:22:51.370673Z","end":"2026-04-16T16:22:51.62631Z","steps":["trace[1469940979] 'process raft request' (duration: 255.420444ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:51.626444Z","caller":"traceutil/trace.go:171","msg":"trace[902835346] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"208.75722ms","start":"2026-04-16T16:22:51.417481Z","end":"2026-04-16T16:22:51.626238Z","steps":["trace[902835346] 'read index received' (duration: 208.710099ms)","trace[902835346] 'applied index is now lower than readState.Index' (duration: 45.701µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:22:51.626563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.063375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:22:51.626633Z","caller":"traceutil/trace.go:171","msg":"trace[2012931551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1843; }","duration":"209.189278ms","start":"2026-04-16T16:22:51.417424Z","end":"2026-04-16T16:22:51.626613Z","steps":["trace[2012931551] 'agreement among raft nodes before linearized reading' (duration: 209.054495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:51.627622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.911697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:22:51.627709Z","caller":"traceutil/trace.go:171","msg":"trace[1051174622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1844; }","duration":"202.036249ms","start":"2026-04-16T16:22:51.425651Z","end":"2026-04-16T16:22:51.627687Z","steps":["trace[1051174622] 'agreement among raft nodes before linearized reading' (duration: 201.750994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:22:51.628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.394552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:22:51.628076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.141214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T16:22:51.628123Z","caller":"traceutil/trace.go:171","msg":"trace[1323843216] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1844; }","duration":"119.552405ms","start":"2026-04-16T16:22:51.508549Z","end":"2026-04-16T16:22:51.628101Z","steps":["trace[1323843216] 'agreement among raft nodes before linearized reading' (duration: 119.388092ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:22:51.628153Z","caller":"traceutil/trace.go:171","msg":"trace[720188889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1844; }","duration":"174.309507ms","start":"2026-04-16T16:22:51.453824Z","end":"2026-04-16T16:22:51.628134Z","steps":["trace[720188889] 'agreement among raft nodes before linearized reading' (duration: 174.062253ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:47.143193Z","caller":"traceutil/trace.go:171","msg":"trace[998067552] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"223.595673ms","start":"2026-04-16T16:23:46.919577Z","end":"2026-04-16T16:23:47.143173Z","steps":["trace[998067552] 'process raft request' (duration: 223.351838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:47.143403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.959597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T16:23:47.143485Z","caller":"traceutil/trace.go:171","msg":"trace[950806137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2194; }","duration":"114.10579ms","start":"2026-04-16T16:23:47.029359Z","end":"2026-04-16T16:23:47.143464Z","steps":["trace[950806137] 'agreement among raft nodes before linearized reading' (duration: 113.855156ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:23:47.143167Z","caller":"traceutil/trace.go:171","msg":"trace[1294697254] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"113.724633ms","start":"2026-04-16T16:23:47.029407Z","end":"2026-04-16T16:23:47.143132Z","steps":["trace[1294697254] 'read index received' (duration: 113.518219ms)","trace[1294697254] 'applied index is now lower than readState.Index' (duration: 205.224µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:23:47.143942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.637775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T16:23:47.144092Z","caller":"traceutil/trace.go:171","msg":"trace[857353510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2194; }","duration":"110.780048ms","start":"2026-04-16T16:23:47.03326Z","end":"2026-04-16T16:23:47.14404Z","steps":["trace[857353510] 'agreement among raft nodes before linearized reading' (duration: 110.570684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:23:47.503176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.345843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:23:47.503303Z","caller":"traceutil/trace.go:171","msg":"trace[649312884] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2280; }","duration":"251.750917ms","start":"2026-04-16T16:23:47.251531Z","end":"2026-04-16T16:23:47.503282Z","steps":["trace[649312884] 'read index received' (duration: 31.897294ms)","trace[649312884] 'applied index is now lower than readState.Index' (duration: 219.851812ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:23:47.503468Z","caller":"traceutil/trace.go:171","msg":"trace[1622668100] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"355.39609ms","start":"2026-04-16T16:23:47.148002Z","end":"2026-04-16T16:23:47.503398Z","steps":["trace[1622668100] 'process raft request' (duration: 135.540998ms)","trace[1622668100] 'compare' (duration: 218.964305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:23:47.503606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.065782ms","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":"warn","ts":"2026-04-16T16:23:47.503856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:23:47.147994Z","time spent":"355.559973ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:23:47.503927Z","caller":"traceutil/trace.go:171","msg":"trace[1231267201] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2196; }","duration":"252.388838ms","start":"2026-04-16T16:23:47.251482Z","end":"2026-04-16T16:23:47.503871Z","steps":["trace[1231267201] 'agreement among raft nodes before linearized reading' (duration: 251.858509ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:22.12337Z","caller":"traceutil/trace.go:171","msg":"trace[1552988657] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"122.78176ms","start":"2026-04-16T16:24:22.000565Z","end":"2026-04-16T16:24:22.123347Z","steps":["trace[1552988657] 'process raft request' (duration: 122.605517ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:55.598012Z","caller":"traceutil/trace.go:171","msg":"trace[262118893] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"121.864474ms","start":"2026-04-16T16:24:55.476123Z","end":"2026-04-16T16:24:55.597987Z","steps":["trace[262118893] 'process raft request' (duration: 121.702621ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:24:55.606442Z","caller":"traceutil/trace.go:171","msg":"trace[164382276] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"129.309654ms","start":"2026-04-16T16:24:55.477107Z","end":"2026-04-16T16:24:55.606416Z","steps":["trace[164382276] 'process raft request' (duration: 129.185252ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:21.564394Z","caller":"traceutil/trace.go:171","msg":"trace[1124280492] transaction","detail":"{read_only:false; response_revision:3736; number_of_response:1; }","duration":"143.631646ms","start":"2026-04-16T16:27:21.420735Z","end":"2026-04-16T16:27:21.564367Z","steps":["trace[1124280492] 'process raft request' (duration: 143.397562ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:28:46.592251Z","caller":"traceutil/trace.go:171","msg":"trace[1988503174] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"103.790369ms","start":"2026-04-16T16:28:46.488437Z","end":"2026-04-16T16:28:46.592228Z","steps":["trace[1988503174] 'process raft request' (duration: 77.911267ms)","trace[1988503174] 'compare' (duration: 25.475284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:28:46.592336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.301749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:73579"} {"level":"info","ts":"2026-04-16T16:28:46.592398Z","caller":"traceutil/trace.go:171","msg":"trace[1885123882] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4799; }","duration":"103.39172ms","start":"2026-04-16T16:28:46.488992Z","end":"2026-04-16T16:28:46.592384Z","steps":["trace[1885123882] 'agreement among raft nodes before linearized reading' (duration: 103.124776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:28:46.599149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.737217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196043"} {"level":"info","ts":"2026-04-16T16:28:46.599234Z","caller":"traceutil/trace.go:171","msg":"trace[1150403911] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4799; }","duration":"103.833219ms","start":"2026-04-16T16:28:46.495374Z","end":"2026-04-16T16:28:46.599207Z","steps":["trace[1150403911] 'agreement among raft nodes before linearized reading' (duration: 96.597153ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:28:52.003906Z","caller":"traceutil/trace.go:171","msg":"trace[1232894513] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"135.441958ms","start":"2026-04-16T16:28:51.868435Z","end":"2026-04-16T16:28:52.003877Z","steps":["trace[1232894513] 'process raft request' (duration: 135.223344ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:01.205993Z","caller":"traceutil/trace.go:171","msg":"trace[125285598] linearizableReadLoop","detail":"{readStateIndex:5370; appliedIndex:5369; }","duration":"100.784081ms","start":"2026-04-16T16:29:01.105185Z","end":"2026-04-16T16:29:01.205969Z","steps":["trace[125285598] 'read index received' (duration: 12.63886ms)","trace[125285598] 'applied index is now lower than readState.Index' (duration: 88.143861ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:29:01.206117Z","caller":"traceutil/trace.go:171","msg":"trace[1323573804] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"102.026504ms","start":"2026-04-16T16:29:01.104065Z","end":"2026-04-16T16:29:01.206092Z","steps":["trace[1323573804] 'process raft request' (duration: 101.656207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:01.206483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.279011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70566"} {"level":"info","ts":"2026-04-16T16:29:01.206527Z","caller":"traceutil/trace.go:171","msg":"trace[266443169] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5171; }","duration":"101.354253ms","start":"2026-04-16T16:29:01.105161Z","end":"2026-04-16T16:29:01.206516Z","steps":["trace[266443169] 'agreement among raft nodes before linearized reading' (duration: 101.132378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:09.923071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.415067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:29:09.923229Z","caller":"traceutil/trace.go:171","msg":"trace[1957983249] linearizableReadLoop","detail":"{readStateIndex:5547; appliedIndex:5546; }","duration":"169.919312ms","start":"2026-04-16T16:29:09.753262Z","end":"2026-04-16T16:29:09.923206Z","steps":["trace[1957983249] 'read index received' (duration: 33.21901ms)","trace[1957983249] 'applied index is now lower than readState.Index' (duration: 136.698352ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:29:09.923343Z","caller":"traceutil/trace.go:171","msg":"trace[1120713978] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"196.106139ms","start":"2026-04-16T16:29:09.727206Z","end":"2026-04-16T16:29:09.923312Z","steps":["trace[1120713978] 'process raft request' (duration: 59.333525ms)","trace[1120713978] 'compare' (duration: 136.191063ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:29:09.923473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.249342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T16:29:09.923501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.216318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-16T16:29:09.923535Z","caller":"traceutil/trace.go:171","msg":"trace[1266016906] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5343; }","duration":"147.347704ms","start":"2026-04-16T16:29:09.776172Z","end":"2026-04-16T16:29:09.92352Z","steps":["trace[1266016906] 'agreement among raft nodes before linearized reading' (duration: 147.163801ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:09.923556Z","caller":"traceutil/trace.go:171","msg":"trace[1584356048] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5343; }","duration":"170.32532ms","start":"2026-04-16T16:29:09.753215Z","end":"2026-04-16T16:29:09.923541Z","steps":["trace[1584356048] 'agreement among raft nodes before linearized reading' (duration: 170.109696ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:10.131999Z","caller":"traceutil/trace.go:171","msg":"trace[870423564] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"112.34086ms","start":"2026-04-16T16:29:10.01963Z","end":"2026-04-16T16:29:10.131971Z","steps":["trace[870423564] 'process raft request' (duration: 107.036499ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:10.132186Z","caller":"traceutil/trace.go:171","msg":"trace[629291981] linearizableReadLoop","detail":"{readStateIndex:5549; appliedIndex:5548; }","duration":"110.922093ms","start":"2026-04-16T16:29:10.021243Z","end":"2026-04-16T16:29:10.132165Z","steps":["trace[629291981] 'read index received' (duration: 105.45701ms)","trace[629291981] 'applied index is now lower than readState.Index' (duration: 5.463443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:29:10.132401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.161798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-16T16:29:10.132458Z","caller":"traceutil/trace.go:171","msg":"trace[802888531] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5345; }","duration":"111.252569ms","start":"2026-04-16T16:29:10.02119Z","end":"2026-04-16T16:29:10.132443Z","steps":["trace[802888531] 'agreement among raft nodes before linearized reading' (duration: 111.047316ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:22.262387Z","caller":"traceutil/trace.go:171","msg":"trace[968035001] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"112.674486ms","start":"2026-04-16T16:29:22.149687Z","end":"2026-04-16T16:29:22.262361Z","steps":["trace[968035001] 'process raft request' (duration: 112.501793ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:46.983536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3047} {"level":"info","ts":"2026-04-16T16:30:47.066746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3047,"took":"82.044445ms","hash":994203349,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":31956992,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-16T16:30:47.066799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":994203349,"revision":3047,"compact-revision":-1} {"level":"info","ts":"2026-04-16T16:35:13.275398Z","caller":"traceutil/trace.go:171","msg":"trace[434308189] transaction","detail":"{read_only:false; response_revision:8164; number_of_response:1; }","duration":"247.798781ms","start":"2026-04-16T16:35:13.027569Z","end":"2026-04-16T16:35:13.275368Z","steps":["trace[434308189] 'process raft request' (duration: 247.633538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:24.846928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.805481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:35:24.847135Z","caller":"traceutil/trace.go:171","msg":"trace[821890415] linearizableReadLoop","detail":"{readStateIndex:8516; appliedIndex:8514; }","duration":"159.735111ms","start":"2026-04-16T16:35:24.687379Z","end":"2026-04-16T16:35:24.847114Z","steps":["trace[821890415] 'read index received' (duration: 143.551247ms)","trace[821890415] 'applied index is now lower than readState.Index' (duration: 16.182654ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:35:24.847176Z","caller":"traceutil/trace.go:171","msg":"trace[505518072] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"187.137317ms","start":"2026-04-16T16:35:24.660022Z","end":"2026-04-16T16:35:24.847159Z","steps":["trace[505518072] 'process raft request' (duration: 187.014065ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:24.847217Z","caller":"traceutil/trace.go:171","msg":"trace[1554908541] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"342.876553ms","start":"2026-04-16T16:35:24.504292Z","end":"2026-04-16T16:35:24.847169Z","steps":["trace[1554908541] 'process raft request' (duration: 42.734996ms)","trace[1554908541] 'compare' (duration: 299.685539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:35:24.847359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.971185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-16T16:35:24.84738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:24.50427Z","time spent":"343.028266ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:35:24.847421Z","caller":"traceutil/trace.go:171","msg":"trace[1397349125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8223; }","duration":"160.073189ms","start":"2026-04-16T16:35:24.687333Z","end":"2026-04-16T16:35:24.847406Z","steps":["trace[1397349125] 'agreement among raft nodes before linearized reading' (duration: 159.903004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:24.84749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.723422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T16:35:24.847583Z","caller":"traceutil/trace.go:171","msg":"trace[139445968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8223; }","duration":"158.853414ms","start":"2026-04-16T16:35:24.688708Z","end":"2026-04-16T16:35:24.847562Z","steps":["trace[139445968] 'agreement among raft nodes before linearized reading' (duration: 158.585518ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:25.798707Z","caller":"traceutil/trace.go:171","msg":"trace[1525652090] transaction","detail":"{read_only:false; response_revision:8229; number_of_response:1; }","duration":"228.745532ms","start":"2026-04-16T16:35:25.569919Z","end":"2026-04-16T16:35:25.798665Z","steps":["trace[1525652090] 'process raft request' (duration: 228.464527ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:26.613942Z","caller":"traceutil/trace.go:171","msg":"trace[283765595] transaction","detail":"{read_only:false; response_revision:8231; number_of_response:1; }","duration":"107.232391ms","start":"2026-04-16T16:35:26.506684Z","end":"2026-04-16T16:35:26.613917Z","steps":["trace[283765595] 'process raft request' (duration: 107.065428ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:27.039367Z","caller":"traceutil/trace.go:171","msg":"trace[294943443] linearizableReadLoop","detail":"{readStateIndex:8527; appliedIndex:8526; }","duration":"144.532875ms","start":"2026-04-16T16:35:26.894796Z","end":"2026-04-16T16:35:27.039329Z","steps":["trace[294943443] 'read index received' (duration: 98.829744ms)","trace[294943443] 'applied index is now lower than readState.Index' (duration: 45.701301ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:35:27.039467Z","caller":"traceutil/trace.go:171","msg":"trace[1108302976] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"186.71509ms","start":"2026-04-16T16:35:26.852723Z","end":"2026-04-16T16:35:27.039438Z","steps":["trace[1108302976] 'process raft request' (duration: 140.966808ms)","trace[1108302976] 'compare' (duration: 45.464477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:35:27.039667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.786051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T16:35:27.039721Z","caller":"traceutil/trace.go:171","msg":"trace[1266347277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8233; }","duration":"125.900193ms","start":"2026-04-16T16:35:26.913804Z","end":"2026-04-16T16:35:27.039704Z","steps":["trace[1266347277] 'agreement among raft nodes before linearized reading' (duration: 125.71866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:27.039711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.900642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T16:35:27.03994Z","caller":"traceutil/trace.go:171","msg":"trace[160526426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8233; }","duration":"145.189587ms","start":"2026-04-16T16:35:26.894733Z","end":"2026-04-16T16:35:27.039922Z","steps":["trace[160526426] 'agreement among raft nodes before linearized reading' (duration: 144.78264ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:27.198839Z","caller":"traceutil/trace.go:171","msg":"trace[2095942322] transaction","detail":"{read_only:false; response_revision:8235; number_of_response:1; }","duration":"154.164346ms","start":"2026-04-16T16:35:27.04464Z","end":"2026-04-16T16:35:27.198804Z","steps":["trace[2095942322] 'process raft request' (duration: 116.645569ms)","trace[2095942322] 'compare' (duration: 37.337724ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:35:46.995203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6179} {"level":"info","ts":"2026-04-16T16:35:47.090255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6179,"took":"93.698077ms","hash":862995117,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":23162880,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-16T16:35:47.090341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":862995117,"revision":6179,"compact-revision":3047} {"level":"info","ts":"2026-04-16T16:35:54.182916Z","caller":"traceutil/trace.go:171","msg":"trace[1298404711] linearizableReadLoop","detail":"{readStateIndex:8738; appliedIndex:8737; }","duration":"164.927588ms","start":"2026-04-16T16:35:54.017962Z","end":"2026-04-16T16:35:54.182889Z","steps":["trace[1298404711] 'read index received' (duration: 164.687014ms)","trace[1298404711] 'applied index is now lower than readState.Index' (duration: 239.044µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:35:54.183115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.131202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:35:54.183043Z","caller":"traceutil/trace.go:171","msg":"trace[965875599] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"251.058122ms","start":"2026-04-16T16:35:53.931933Z","end":"2026-04-16T16:35:54.182991Z","steps":["trace[965875599] 'process raft request' (duration: 250.796587ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:54.183158Z","caller":"traceutil/trace.go:171","msg":"trace[1997300378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8436; }","duration":"165.214484ms","start":"2026-04-16T16:35:54.017933Z","end":"2026-04-16T16:35:54.183147Z","steps":["trace[1997300378] 'agreement among raft nodes before linearized reading' (duration: 165.067421ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:54.357644Z","caller":"traceutil/trace.go:171","msg":"trace[1843911985] linearizableReadLoop","detail":"{readStateIndex:8739; appliedIndex:8738; }","duration":"148.052901ms","start":"2026-04-16T16:35:54.209565Z","end":"2026-04-16T16:35:54.357618Z","steps":["trace[1843911985] 'read index received' (duration: 145.762048ms)","trace[1843911985] 'applied index is now lower than readState.Index' (duration: 2.289853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:35:54.357806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.193834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:35:54.357862Z","caller":"traceutil/trace.go:171","msg":"trace[1327587877] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8437; }","duration":"148.336307ms","start":"2026-04-16T16:35:54.209511Z","end":"2026-04-16T16:35:54.357848Z","steps":["trace[1327587877] 'agreement among raft nodes before linearized reading' (duration: 148.202774ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:54.358037Z","caller":"traceutil/trace.go:171","msg":"trace[1106493794] transaction","detail":"{read_only:false; response_revision:8437; number_of_response:1; }","duration":"165.658453ms","start":"2026-04-16T16:35:54.192352Z","end":"2026-04-16T16:35:54.358011Z","steps":["trace[1106493794] 'process raft request' (duration: 162.899221ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:07.371462Z","caller":"traceutil/trace.go:171","msg":"trace[1827969966] linearizableReadLoop","detail":"{readStateIndex:8856; appliedIndex:8855; }","duration":"134.465445ms","start":"2026-04-16T16:36:07.23698Z","end":"2026-04-16T16:36:07.371445Z","steps":["trace[1827969966] 'read index received' (duration: 134.317032ms)","trace[1827969966] 'applied index is now lower than readState.Index' (duration: 147.883µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:36:07.371525Z","caller":"traceutil/trace.go:171","msg":"trace[284026966] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"210.228913ms","start":"2026-04-16T16:36:07.161271Z","end":"2026-04-16T16:36:07.3715Z","steps":["trace[284026966] 'process raft request' (duration: 210.06422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:07.371597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.614268ms","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-16T16:36:07.371618Z","caller":"traceutil/trace.go:171","msg":"trace[1654948507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8548; }","duration":"134.69903ms","start":"2026-04-16T16:36:07.236914Z","end":"2026-04-16T16:36:07.371613Z","steps":["trace[1654948507] 'agreement among raft nodes before linearized reading' (duration: 134.599448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:08.09244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.208476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-16T16:36:08.092532Z","caller":"traceutil/trace.go:171","msg":"trace[1882834256] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8555; }","duration":"172.307439ms","start":"2026-04-16T16:36:07.920195Z","end":"2026-04-16T16:36:08.092502Z","steps":["trace[1882834256] 'range keys from in-memory index tree' (duration: 172.051464ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:08.442266Z","caller":"traceutil/trace.go:171","msg":"trace[1057710765] linearizableReadLoop","detail":"{readStateIndex:8864; appliedIndex:8863; }","duration":"187.225769ms","start":"2026-04-16T16:36:08.255015Z","end":"2026-04-16T16:36:08.442241Z","steps":["trace[1057710765] 'read index received' (duration: 187.031865ms)","trace[1057710765] 'applied index is now lower than readState.Index' (duration: 192.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:36:08.442348Z","caller":"traceutil/trace.go:171","msg":"trace[1978782711] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"241.270799ms","start":"2026-04-16T16:36:08.201041Z","end":"2026-04-16T16:36:08.442312Z","steps":["trace[1978782711] 'process raft request' (duration: 241.059424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:08.442477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.441353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T16:36:08.442568Z","caller":"traceutil/trace.go:171","msg":"trace[567405462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8556; }","duration":"187.600026ms","start":"2026-04-16T16:36:08.254956Z","end":"2026-04-16T16:36:08.442556Z","steps":["trace[567405462] 'agreement among raft nodes before linearized reading' (duration: 187.407023ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:56.7188Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002453.wal"} {"level":"info","ts":"2026-04-16T16:37:24.041169Z","caller":"traceutil/trace.go:171","msg":"trace[1009494019] transaction","detail":"{read_only:false; response_revision:9297; number_of_response:1; }","duration":"222.739059ms","start":"2026-04-16T16:37:23.818392Z","end":"2026-04-16T16:37:24.041131Z","steps":["trace[1009494019] 'process raft request' (duration: 222.486264ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:37:24.048356Z","caller":"traceutil/trace.go:171","msg":"trace[1569824926] transaction","detail":"{read_only:false; response_revision:9299; number_of_response:1; }","duration":"204.015826ms","start":"2026-04-16T16:37:23.844305Z","end":"2026-04-16T16:37:24.04832Z","steps":["trace[1569824926] 'process raft request' (duration: 203.75763ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:37:24.048356Z","caller":"traceutil/trace.go:171","msg":"trace[2141522456] transaction","detail":"{read_only:false; response_revision:9298; number_of_response:1; }","duration":"226.02296ms","start":"2026-04-16T16:37:23.822284Z","end":"2026-04-16T16:37:24.048307Z","steps":["trace[2141522456] 'process raft request' (duration: 225.635052ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:14.119806Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T16:38:14.133782Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T16:38:14.133941Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T16:40:47.002382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8350} {"level":"info","ts":"2026-04-16T16:40:47.077369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8350,"took":"72.738082ms","hash":2272201547,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":26685440,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-16T16:40:47.077439Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2272201547,"revision":8350,"compact-revision":6179} {"level":"info","ts":"2026-04-16T16:45:47.008558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11028} {"level":"info","ts":"2026-04-16T16:45:47.090284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11028,"took":"79.046399ms","hash":3211072246,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":28434432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-16T16:45:47.090347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3211072246,"revision":11028,"compact-revision":8350} {"level":"warn","ts":"2026-04-16T16:47:52.082932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.607489ms","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-16T16:47:52.083291Z","caller":"traceutil/trace.go:171","msg":"trace[1616971558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13835; }","duration":"141.777792ms","start":"2026-04-16T16:47:51.941241Z","end":"2026-04-16T16:47:52.083019Z","steps":["trace[1616971558] 'range keys from in-memory index tree' (duration: 141.397565ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:52.277666Z","caller":"traceutil/trace.go:171","msg":"trace[395339598] transaction","detail":"{read_only:false; response_revision:13836; number_of_response:1; }","duration":"188.595503ms","start":"2026-04-16T16:47:52.089044Z","end":"2026-04-16T16:47:52.277639Z","steps":["trace[395339598] 'process raft request' (duration: 188.40907ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:48:13.621561Z","caller":"traceutil/trace.go:171","msg":"trace[955356855] linearizableReadLoop","detail":"{readStateIndex:14629; appliedIndex:14628; }","duration":"238.124487ms","start":"2026-04-16T16:48:13.383404Z","end":"2026-04-16T16:48:13.621528Z","steps":["trace[955356855] 'read index received' (duration: 237.918603ms)","trace[955356855] 'applied index is now lower than readState.Index' (duration: 204.854µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:48:13.62172Z","caller":"traceutil/trace.go:171","msg":"trace[1638961585] transaction","detail":"{read_only:false; response_revision:14137; number_of_response:1; }","duration":"239.875209ms","start":"2026-04-16T16:48:13.381816Z","end":"2026-04-16T16:48:13.621691Z","steps":["trace[1638961585] 'process raft request' (duration: 239.508042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:48:13.621848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.427403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T16:48:13.621931Z","caller":"traceutil/trace.go:171","msg":"trace[687262222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14137; }","duration":"238.508124ms","start":"2026-04-16T16:48:13.383365Z","end":"2026-04-16T16:48:13.621874Z","steps":["trace[687262222] 'agreement among raft nodes before linearized reading' (duration: 238.32834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:48:13.628483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.798541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:48:13.628569Z","caller":"traceutil/trace.go:171","msg":"trace[1362300953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14138; }","duration":"211.895473ms","start":"2026-04-16T16:48:13.416656Z","end":"2026-04-16T16:48:13.628551Z","steps":["trace[1362300953] 'agreement among raft nodes before linearized reading' (duration: 211.75153ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:48:13.628621Z","caller":"traceutil/trace.go:171","msg":"trace[552039278] transaction","detail":"{read_only:false; response_revision:14138; number_of_response:1; }","duration":"245.107618ms","start":"2026-04-16T16:48:13.383407Z","end":"2026-04-16T16:48:13.628514Z","steps":["trace[552039278] 'process raft request' (duration: 244.779172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.184771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.893497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T16:49:32.184885Z","caller":"traceutil/trace.go:171","msg":"trace[1352670006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14910; }","duration":"199.098222ms","start":"2026-04-16T16:49:31.985768Z","end":"2026-04-16T16:49:32.184866Z","steps":["trace[1352670006] 'range keys from in-memory index tree' (duration: 198.723574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.184922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.555874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T16:49:32.184988Z","caller":"traceutil/trace.go:171","msg":"trace[1702489081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14910; }","duration":"195.635017ms","start":"2026-04-16T16:49:31.989335Z","end":"2026-04-16T16:49:32.18497Z","steps":["trace[1702489081] 'range keys from in-memory index tree' (duration: 195.464043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.185201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.525817ms","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-16T16:49:32.185288Z","caller":"traceutil/trace.go:171","msg":"trace[1586681385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14910; }","duration":"201.735081ms","start":"2026-04-16T16:49:31.98353Z","end":"2026-04-16T16:49:32.185265Z","steps":["trace[1586681385] 'range keys from in-memory index tree' (duration: 201.295492ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:32.413709Z","caller":"traceutil/trace.go:171","msg":"trace[245358429] linearizableReadLoop","detail":"{readStateIndex:15425; appliedIndex:15424; }","duration":"224.535832ms","start":"2026-04-16T16:49:32.189154Z","end":"2026-04-16T16:49:32.41369Z","steps":["trace[245358429] 'read index received' (duration: 224.44901ms)","trace[245358429] 'applied index is now lower than readState.Index' (duration: 85.912µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:32.413781Z","caller":"traceutil/trace.go:171","msg":"trace[1035058929] transaction","detail":"{read_only:false; response_revision:14911; number_of_response:1; }","duration":"234.56666ms","start":"2026-04-16T16:49:32.179183Z","end":"2026-04-16T16:49:32.413749Z","steps":["trace[1035058929] 'process raft request' (duration: 234.343836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.413891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.718745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-16T16:49:32.413929Z","caller":"traceutil/trace.go:171","msg":"trace[448806460] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14911; }","duration":"224.787097ms","start":"2026-04-16T16:49:32.189132Z","end":"2026-04-16T16:49:32.413919Z","steps":["trace[448806460] 'agreement among raft nodes before linearized reading' (duration: 224.659314ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:32.653526Z","caller":"traceutil/trace.go:171","msg":"trace[1823006338] transaction","detail":"{read_only:false; response_revision:14912; number_of_response:1; }","duration":"464.186947ms","start":"2026-04-16T16:49:32.189291Z","end":"2026-04-16T16:49:32.653478Z","steps":["trace[1823006338] 'process raft request' (duration: 445.966974ms)","trace[1823006338] 'compare' (duration: 17.894017ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:32.65363Z","caller":"traceutil/trace.go:171","msg":"trace[911183668] transaction","detail":"{read_only:false; response_revision:14913; number_of_response:1; }","duration":"464.247206ms","start":"2026-04-16T16:49:32.189357Z","end":"2026-04-16T16:49:32.653604Z","steps":["trace[911183668] 'process raft request' (duration: 464.009722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.653739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:32.189263Z","time spent":"464.37876ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:49:32.65377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:32.189322Z","time spent":"464.37571ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:49:32.653803Z","caller":"traceutil/trace.go:171","msg":"trace[1739491165] transaction","detail":"{read_only:false; response_revision:14914; number_of_response:1; }","duration":"464.375209ms","start":"2026-04-16T16:49:32.189413Z","end":"2026-04-16T16:49:32.653788Z","steps":["trace[1739491165] 'process raft request' (duration: 464.125204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.653873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:32.189402Z","time spent":"464.440161ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:49:32.654364Z","caller":"traceutil/trace.go:171","msg":"trace[2028771436] linearizableReadLoop","detail":"{readStateIndex:15429; appliedIndex:15425; }","duration":"240.578942ms","start":"2026-04-16T16:49:32.413775Z","end":"2026-04-16T16:49:32.654354Z","steps":["trace[2028771436] 'read index received' (duration: 221.383081ms)","trace[2028771436] 'applied index is now lower than readState.Index' (duration: 19.194841ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:32.654397Z","caller":"traceutil/trace.go:171","msg":"trace[96633086] transaction","detail":"{read_only:false; response_revision:14915; number_of_response:1; }","duration":"381.824323ms","start":"2026-04-16T16:49:32.27254Z","end":"2026-04-16T16:49:32.654364Z","steps":["trace[96633086] 'process raft request' (duration: 381.200762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.654473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.532522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:49:32.654531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.706841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:49:32.654535Z","caller":"traceutil/trace.go:171","msg":"trace[2053567815] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:14915; }","duration":"257.633114ms","start":"2026-04-16T16:49:32.396885Z","end":"2026-04-16T16:49:32.654518Z","steps":["trace[2053567815] 'agreement among raft nodes before linearized reading' (duration: 257.531252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.654546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.7323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-04-16T16:49:32.654572Z","caller":"traceutil/trace.go:171","msg":"trace[1636365426] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:14915; }","duration":"261.750212ms","start":"2026-04-16T16:49:32.39281Z","end":"2026-04-16T16:49:32.65456Z","steps":["trace[1636365426] 'agreement among raft nodes before linearized reading' (duration: 261.6271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.654557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.946974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"warn","ts":"2026-04-16T16:49:32.654563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:32.272516Z","time spent":"381.961838ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:49:32.6546Z","caller":"traceutil/trace.go:171","msg":"trace[590192393] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14915; }","duration":"246.845631ms","start":"2026-04-16T16:49:32.407739Z","end":"2026-04-16T16:49:32.654585Z","steps":["trace[590192393] 'agreement among raft nodes before linearized reading' (duration: 246.655608ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:32.654615Z","caller":"traceutil/trace.go:171","msg":"trace[1639093989] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14915; }","duration":"247.043256ms","start":"2026-04-16T16:49:32.407553Z","end":"2026-04-16T16:49:32.654596Z","steps":["trace[1639093989] 'agreement among raft nodes before linearized reading' (duration: 246.819381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.654479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.232039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:49:32.654766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.548745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-16T16:49:32.654803Z","caller":"traceutil/trace.go:171","msg":"trace[318923904] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14915; }","duration":"247.612096ms","start":"2026-04-16T16:49:32.407182Z","end":"2026-04-16T16:49:32.654794Z","steps":["trace[318923904] 'agreement among raft nodes before linearized reading' (duration: 247.521795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.654902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.652003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:49:32.65491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.345341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:49:32.654922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.567121ms","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-16T16:49:32.65495Z","caller":"traceutil/trace.go:171","msg":"trace[834166999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14915; }","duration":"319.637092ms","start":"2026-04-16T16:49:32.335306Z","end":"2026-04-16T16:49:32.654943Z","steps":["trace[834166999] 'agreement among raft nodes before linearized reading' (duration: 319.560531ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:32.654945Z","caller":"traceutil/trace.go:171","msg":"trace[818214964] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:14915; }","duration":"233.726935ms","start":"2026-04-16T16:49:32.421205Z","end":"2026-04-16T16:49:32.654932Z","steps":["trace[818214964] 'agreement among raft nodes before linearized reading' (duration: 233.666304ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:32.654947Z","caller":"traceutil/trace.go:171","msg":"trace[1864482220] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14915; }","duration":"247.401972ms","start":"2026-04-16T16:49:32.407535Z","end":"2026-04-16T16:49:32.654937Z","steps":["trace[1864482220] 'agreement among raft nodes before linearized reading' (duration: 247.345231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:32.654972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:32.335284Z","time spent":"319.682214ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:49:32.655245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.364329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18868"} {"level":"info","ts":"2026-04-16T16:49:32.655299Z","caller":"traceutil/trace.go:171","msg":"trace[2043370053] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14915; }","duration":"240.4545ms","start":"2026-04-16T16:49:32.414832Z","end":"2026-04-16T16:49:32.655287Z","steps":["trace[2043370053] 'agreement among raft nodes before linearized reading' (duration: 239.89475ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:32.654755Z","caller":"traceutil/trace.go:171","msg":"trace[288729206] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14915; }","duration":"247.529334ms","start":"2026-04-16T16:49:32.407204Z","end":"2026-04-16T16:49:32.654733Z","steps":["trace[288729206] 'agreement among raft nodes before linearized reading' (duration: 247.164076ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:47.016289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13067} {"level":"info","ts":"2026-04-16T16:50:47.092945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13067,"took":"74.029118ms","hash":4187865738,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-16T16:50:47.093012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4187865738,"revision":13067,"compact-revision":11028} {"level":"info","ts":"2026-04-16T16:55:25.017827Z","caller":"traceutil/trace.go:171","msg":"trace[789000272] transaction","detail":"{read_only:false; response_revision:17523; number_of_response:1; }","duration":"158.833109ms","start":"2026-04-16T16:55:24.858961Z","end":"2026-04-16T16:55:25.017794Z","steps":["trace[789000272] 'process raft request' (duration: 85.557815ms)","trace[789000272] 'compare' (duration: 73.014689ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:55:26.042896Z","caller":"traceutil/trace.go:171","msg":"trace[1812261105] linearizableReadLoop","detail":"{readStateIndex:18127; appliedIndex:18126; }","duration":"351.281695ms","start":"2026-04-16T16:55:25.691589Z","end":"2026-04-16T16:55:26.042871Z","steps":["trace[1812261105] 'read index received' (duration: 351.082002ms)","trace[1812261105] 'applied index is now lower than readState.Index' (duration: 198.163µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:55:26.043021Z","caller":"traceutil/trace.go:171","msg":"trace[930998447] transaction","detail":"{read_only:false; response_revision:17526; number_of_response:1; }","duration":"430.650234ms","start":"2026-04-16T16:55:25.612339Z","end":"2026-04-16T16:55:26.042989Z","steps":["trace[930998447] 'process raft request' (duration: 430.353408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:55:26.043147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.53598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-qj78j\" ","response":"range_response_count:1 size:9707"} {"level":"info","ts":"2026-04-16T16:55:26.043291Z","caller":"traceutil/trace.go:171","msg":"trace[71724260] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-qj78j; range_end:; response_count:1; response_revision:17526; }","duration":"351.731754ms","start":"2026-04-16T16:55:25.691547Z","end":"2026-04-16T16:55:26.043279Z","steps":["trace[71724260] 'agreement among raft nodes before linearized reading' (duration: 351.426538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:55:26.043176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:55:25.612315Z","time spent":"430.782857ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:55:26.043333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:55:25.691527Z","time spent":"351.794335ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9730,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-qj78j\" "} {"level":"info","ts":"2026-04-16T16:55:26.077452Z","caller":"traceutil/trace.go:171","msg":"trace[1586994001] transaction","detail":"{read_only:false; response_revision:17527; number_of_response:1; }","duration":"147.383395ms","start":"2026-04-16T16:55:25.930034Z","end":"2026-04-16T16:55:26.077417Z","steps":["trace[1586994001] 'process raft request' (duration: 122.727909ms)","trace[1586994001] 'compare' (duration: 24.140655ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:55:35.162993Z","caller":"traceutil/trace.go:171","msg":"trace[476240923] transaction","detail":"{read_only:false; response_revision:17725; number_of_response:1; }","duration":"110.732822ms","start":"2026-04-16T16:55:35.052233Z","end":"2026-04-16T16:55:35.162966Z","steps":["trace[476240923] 'process raft request' (duration: 110.566579ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:55:47.023647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15391} {"level":"info","ts":"2026-04-16T16:55:47.118875Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15391,"took":"91.998268ms","hash":2411094324,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":32444416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-16T16:55:47.118938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2411094324,"revision":15391,"compact-revision":13067} {"level":"info","ts":"2026-04-16T16:59:08.870119Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T16:59:08.875442Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-16T16:59:08.875565Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-16T17:00:47.031864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17800} {"level":"info","ts":"2026-04-16T17:00:47.13536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17800,"took":"99.846938ms","hash":2201112826,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":34050048,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-16T17:00:47.135442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2201112826,"revision":17800,"compact-revision":15391} {"level":"info","ts":"2026-04-16T17:01:00.003852Z","caller":"traceutil/trace.go:171","msg":"trace[1232562850] transaction","detail":"{read_only:false; response_revision:20210; number_of_response:1; }","duration":"115.24384ms","start":"2026-04-16T17:00:59.888579Z","end":"2026-04-16T17:01:00.003822Z","steps":["trace[1232562850] 'process raft request' (duration: 115.073596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:01:00.190143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.058035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-16T17:01:00.190261Z","caller":"traceutil/trace.go:171","msg":"trace[871074710] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:20210; }","duration":"118.290548ms","start":"2026-04-16T17:01:00.071946Z","end":"2026-04-16T17:01:00.190237Z","steps":["trace[871074710] 'range keys from in-memory index tree' (duration: 117.717388ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:01:00.34018Z","caller":"traceutil/trace.go:171","msg":"trace[175294266] transaction","detail":"{read_only:false; response_revision:20212; number_of_response:1; }","duration":"146.858498ms","start":"2026-04-16T17:01:00.193289Z","end":"2026-04-16T17:01:00.340147Z","steps":["trace[175294266] 'process raft request' (duration: 146.740045ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:01:00.340193Z","caller":"traceutil/trace.go:171","msg":"trace[1825572557] transaction","detail":"{read_only:false; response_revision:20211; number_of_response:1; }","duration":"146.95525ms","start":"2026-04-16T17:01:00.193189Z","end":"2026-04-16T17:01:00.340144Z","steps":["trace[1825572557] 'process raft request' (duration: 146.617053ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:01:00.488253Z","caller":"traceutil/trace.go:171","msg":"trace[929124530] transaction","detail":"{read_only:false; response_revision:20213; number_of_response:1; }","duration":"171.883021ms","start":"2026-04-16T17:01:00.316334Z","end":"2026-04-16T17:01:00.488217Z","steps":["trace[929124530] 'process raft request' (duration: 160.20063ms)","trace[929124530] 'compare' (duration: 11.367685ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:01:00.488433Z","caller":"traceutil/trace.go:171","msg":"trace[963602754] transaction","detail":"{read_only:false; response_revision:20215; number_of_response:1; }","duration":"106.430373ms","start":"2026-04-16T17:01:00.381968Z","end":"2026-04-16T17:01:00.488398Z","steps":["trace[963602754] 'process raft request' (duration: 106.380822ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:01:00.488621Z","caller":"traceutil/trace.go:171","msg":"trace[1364764239] transaction","detail":"{read_only:false; response_revision:20214; number_of_response:1; }","duration":"143.551015ms","start":"2026-04-16T17:01:00.345058Z","end":"2026-04-16T17:01:00.488609Z","steps":["trace[1364764239] 'process raft request' (duration: 143.170108ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:01:00.48869Z","caller":"traceutil/trace.go:171","msg":"trace[1038438610] linearizableReadLoop","detail":"{readStateIndex:20901; appliedIndex:20900; }","duration":"148.58035ms","start":"2026-04-16T17:01:00.340097Z","end":"2026-04-16T17:01:00.488677Z","steps":["trace[1038438610] 'read index received' (duration: 136.398ms)","trace[1038438610] 'applied index is now lower than readState.Index' (duration: 12.18027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:01:00.488885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.910812ms","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-16T17:01:00.488927Z","caller":"traceutil/trace.go:171","msg":"trace[2115833719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20215; }","duration":"191.001673ms","start":"2026-04-16T17:01:00.297913Z","end":"2026-04-16T17:01:00.488915Z","steps":["trace[2115833719] 'agreement among raft nodes before linearized reading' (duration: 190.84347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:01:00.488955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.952294ms","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-16T17:01:00.489075Z","caller":"traceutil/trace.go:171","msg":"trace[1290693223] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20215; }","duration":"162.111677ms","start":"2026-04-16T17:01:00.326933Z","end":"2026-04-16T17:01:00.489045Z","steps":["trace[1290693223] 'agreement among raft nodes before linearized reading' (duration: 161.800172ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:01:00.696447Z","caller":"traceutil/trace.go:171","msg":"trace[47774109] transaction","detail":"{read_only:false; response_revision:20216; number_of_response:1; }","duration":"206.513776ms","start":"2026-04-16T17:01:00.489894Z","end":"2026-04-16T17:01:00.696408Z","steps":["trace[47774109] 'process raft request' (duration: 125.790499ms)","trace[47774109] 'compare' (duration: 80.507193ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:01:00.696752Z","caller":"traceutil/trace.go:171","msg":"trace[2008974584] linearizableReadLoop","detail":"{readStateIndex:20904; appliedIndex:20903; }","duration":"205.502967ms","start":"2026-04-16T17:01:00.491229Z","end":"2026-04-16T17:01:00.696732Z","steps":["trace[2008974584] 'read index received' (duration: 124.326181ms)","trace[2008974584] 'applied index is now lower than readState.Index' (duration: 81.175126ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:01:00.697069Z","caller":"traceutil/trace.go:171","msg":"trace[1331199092] transaction","detail":"{read_only:false; response_revision:20217; number_of_response:1; }","duration":"203.910718ms","start":"2026-04-16T17:01:00.493148Z","end":"2026-04-16T17:01:00.697059Z","steps":["trace[1331199092] 'process raft request' (duration: 203.243625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:01:00.69705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.800123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-04-16T17:01:00.697192Z","caller":"traceutil/trace.go:171","msg":"trace[551400608] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:20216; }","duration":"206.029638ms","start":"2026-04-16T17:01:00.491143Z","end":"2026-04-16T17:01:00.697173Z","steps":["trace[551400608] 'agreement among raft nodes before linearized reading' (duration: 205.688951ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:01:00.697281Z","caller":"traceutil/trace.go:171","msg":"trace[1422096555] transaction","detail":"{read_only:false; response_revision:20218; number_of_response:1; }","duration":"203.350787ms","start":"2026-04-16T17:01:00.493923Z","end":"2026-04-16T17:01:00.697274Z","steps":["trace[1422096555] 'process raft request' (duration: 203.083582ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:01:01.406794Z","caller":"traceutil/trace.go:171","msg":"trace[1154507213] transaction","detail":"{read_only:false; response_revision:20219; number_of_response:1; }","duration":"119.388979ms","start":"2026-04-16T17:01:01.287379Z","end":"2026-04-16T17:01:01.406768Z","steps":["trace[1154507213] 'process raft request' (duration: 119.196256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:01:01.697819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.225831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:01:01.697936Z","caller":"traceutil/trace.go:171","msg":"trace[577010584] linearizableReadLoop","detail":"{readStateIndex:20908; appliedIndex:20907; }","duration":"280.424735ms","start":"2026-04-16T17:01:01.417494Z","end":"2026-04-16T17:01:01.697919Z","steps":["trace[577010584] 'read index received' (duration: 36.866887ms)","trace[577010584] 'applied index is now lower than readState.Index' (duration: 243.556498ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:01:01.698076Z","caller":"traceutil/trace.go:171","msg":"trace[1300283295] transaction","detail":"{read_only:false; response_revision:20220; number_of_response:1; }","duration":"401.784111ms","start":"2026-04-16T17:01:01.296246Z","end":"2026-04-16T17:01:01.69803Z","steps":["trace[1300283295] 'process raft request' (duration: 158.253384ms)","trace[1300283295] 'compare' (duration: 242.813053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:01:01.698178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.681159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:01:01.698215Z","caller":"traceutil/trace.go:171","msg":"trace[553899800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20220; }","duration":"280.751611ms","start":"2026-04-16T17:01:01.417453Z","end":"2026-04-16T17:01:01.698205Z","steps":["trace[553899800] 'agreement among raft nodes before linearized reading' (duration: 280.68169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:01:01.698234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.624131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:01:01.698314Z","caller":"traceutil/trace.go:171","msg":"trace[1838501700] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:20220; }","duration":"278.759434ms","start":"2026-04-16T17:01:01.419534Z","end":"2026-04-16T17:01:01.698294Z","steps":["trace[1838501700] 'agreement among raft nodes before linearized reading' (duration: 278.625411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:01:01.698333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:01:01.296222Z","time spent":"401.973225ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:04:53.312076Z","caller":"traceutil/trace.go:171","msg":"trace[1262449132] transaction","detail":"{read_only:false; response_revision:22592; number_of_response:1; }","duration":"121.972958ms","start":"2026-04-16T17:04:53.190073Z","end":"2026-04-16T17:04:53.312046Z","steps":["trace[1262449132] 'process raft request' (duration: 121.752624ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:05:47.038566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20145} {"level":"info","ts":"2026-04-16T17:05:47.146395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20145,"took":"104.481776ms","hash":1863346125,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":41795584,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-16T17:05:47.146472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1863346125,"revision":20145,"compact-revision":17800} {"level":"warn","ts":"2026-04-16T17:08:06.937885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.44929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T17:08:06.93799Z","caller":"traceutil/trace.go:171","msg":"trace[1124152426] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24647; }","duration":"142.621063ms","start":"2026-04-16T17:08:06.795347Z","end":"2026-04-16T17:08:06.937968Z","steps":["trace[1124152426] 'count revisions from in-memory index tree' (duration: 142.325688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:06.937926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.270683ms","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-16T17:08:06.938146Z","caller":"traceutil/trace.go:171","msg":"trace[1642162845] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24647; }","duration":"182.506957ms","start":"2026-04-16T17:08:06.755609Z","end":"2026-04-16T17:08:06.938116Z","steps":["trace[1642162845] 'range keys from in-memory index tree' (duration: 182.046849ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:06.961362Z","caller":"traceutil/trace.go:171","msg":"trace[443934343] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"117.835296ms","start":"2026-04-16T17:08:06.84349Z","end":"2026-04-16T17:08:06.961325Z","steps":["trace[443934343] 'process raft request' (duration: 117.617082ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:07.178976Z","caller":"traceutil/trace.go:171","msg":"trace[787167656] linearizableReadLoop","detail":"{readStateIndex:25462; appliedIndex:25461; }","duration":"217.428807ms","start":"2026-04-16T17:08:06.961277Z","end":"2026-04-16T17:08:07.178706Z","steps":["trace[787167656] 'read index received' (duration: 189.811745ms)","trace[787167656] 'applied index is now lower than readState.Index' (duration: 27.615762ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:07.178899Z","caller":"traceutil/trace.go:171","msg":"trace[895051537] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"247.725249ms","start":"2026-04-16T17:08:06.931092Z","end":"2026-04-16T17:08:07.178817Z","steps":["trace[895051537] 'process raft request' (duration: 220.085378ms)","trace[895051537] 'compare' (duration: 27.384507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:07.179204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.192581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-76cb1f19-58b7-4580-8d4e-b0af9ff62a8f\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-04-16T17:08:07.179306Z","caller":"traceutil/trace.go:171","msg":"trace[382992223] range","detail":"{range_begin:/registry/persistentvolumes/pvc-76cb1f19-58b7-4580-8d4e-b0af9ff62a8f; range_end:; response_count:1; response_revision:24649; }","duration":"238.337194ms","start":"2026-04-16T17:08:06.940942Z","end":"2026-04-16T17:08:07.17928Z","steps":["trace[382992223] 'agreement among raft nodes before linearized reading' (duration: 238.037257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:07.505858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.241399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:07.506218Z","caller":"traceutil/trace.go:171","msg":"trace[798253105] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"540.789246ms","start":"2026-04-16T17:08:06.965382Z","end":"2026-04-16T17:08:07.506171Z","steps":["trace[798253105] 'process raft request' (duration: 540.577353ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:07.506312Z","caller":"traceutil/trace.go:171","msg":"trace[55243660] linearizableReadLoop","detail":"{readStateIndex:25464; appliedIndex:25462; }","duration":"327.194691ms","start":"2026-04-16T17:08:07.17907Z","end":"2026-04-16T17:08:07.506264Z","steps":["trace[55243660] 'read index received' (duration: 212.542725ms)","trace[55243660] 'applied index is now lower than readState.Index' (duration: 114.646216ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:07.506082Z","caller":"traceutil/trace.go:171","msg":"trace[1523353245] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"163.763033ms","start":"2026-04-16T17:08:07.342299Z","end":"2026-04-16T17:08:07.506062Z","steps":["trace[1523353245] 'process raft request' (duration: 163.718323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:07.506416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:06.96534Z","time spent":"540.946009ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:07.506454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.506494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:08:07.506502Z","caller":"traceutil/trace.go:171","msg":"trace[1771504493] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24652; }","duration":"411.626896ms","start":"2026-04-16T17:08:07.094859Z","end":"2026-04-16T17:08:07.506486Z","steps":["trace[1771504493] 'agreement among raft nodes before linearized reading' (duration: 411.487214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:07.506527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:07.094833Z","time spent":"411.687207ms","remote":"127.0.0.1:46546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-16T17:08:07.506695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.054433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-04-16T17:08:07.506761Z","caller":"traceutil/trace.go:171","msg":"trace[1177567503] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24652; }","duration":"322.131375ms","start":"2026-04-16T17:08:07.184603Z","end":"2026-04-16T17:08:07.506735Z","steps":["trace[1177567503] 'agreement among raft nodes before linearized reading' (duration: 321.87203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:07.506799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.471482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-16T17:08:07.506807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:07.184578Z","time spent":"322.218677ms","remote":"127.0.0.1:46472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-16T17:08:07.506834Z","caller":"traceutil/trace.go:171","msg":"trace[347291257] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24652; }","duration":"277.551054ms","start":"2026-04-16T17:08:07.229274Z","end":"2026-04-16T17:08:07.506825Z","steps":["trace[347291257] 'agreement among raft nodes before linearized reading' (duration: 277.504353ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:07.506139Z","caller":"traceutil/trace.go:171","msg":"trace[838415080] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"541.805775ms","start":"2026-04-16T17:08:06.964272Z","end":"2026-04-16T17:08:07.506078Z","steps":["trace[838415080] 'process raft request' (duration: 427.24313ms)","trace[838415080] 'compare' (duration: 114.091286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:07.507069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:06.96422Z","time spent":"542.730883ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:08.148139Z","caller":"traceutil/trace.go:171","msg":"trace[1059849928] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"112.20506ms","start":"2026-04-16T17:08:08.035901Z","end":"2026-04-16T17:08:08.148106Z","steps":["trace[1059849928] 'process raft request' (duration: 111.69788ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:08.148531Z","caller":"traceutil/trace.go:171","msg":"trace[1457150306] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"102.443535ms","start":"2026-04-16T17:08:08.04607Z","end":"2026-04-16T17:08:08.148513Z","steps":["trace[1457150306] 'process raft request' (duration: 101.707461ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:08.149165Z","caller":"traceutil/trace.go:171","msg":"trace[142813569] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"100.308395ms","start":"2026-04-16T17:08:08.048842Z","end":"2026-04-16T17:08:08.149151Z","steps":["trace[142813569] 'process raft request' (duration: 98.98148ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:08.435537Z","caller":"traceutil/trace.go:171","msg":"trace[1767035824] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"236.332305ms","start":"2026-04-16T17:08:08.199175Z","end":"2026-04-16T17:08:08.435508Z","steps":["trace[1767035824] 'process raft request' (duration: 219.523227ms)","trace[1767035824] 'compare' (duration: 16.653465ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:08.438846Z","caller":"traceutil/trace.go:171","msg":"trace[294779912] linearizableReadLoop","detail":"{readStateIndex:25470; appliedIndex:25468; }","duration":"206.124174ms","start":"2026-04-16T17:08:08.232688Z","end":"2026-04-16T17:08:08.438812Z","steps":["trace[294779912] 'read index received' (duration: 185.996454ms)","trace[294779912] 'applied index is now lower than readState.Index' (duration: 20.1262ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:08.439288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.572392ms","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-16T17:08:08.439401Z","caller":"traceutil/trace.go:171","msg":"trace[1863552864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24658; }","duration":"206.704104ms","start":"2026-04-16T17:08:08.232682Z","end":"2026-04-16T17:08:08.439386Z","steps":["trace[1863552864] 'agreement among raft nodes before linearized reading' (duration: 206.390479ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:08.4389Z","caller":"traceutil/trace.go:171","msg":"trace[1479596378] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"238.866543ms","start":"2026-04-16T17:08:08.199996Z","end":"2026-04-16T17:08:08.438863Z","steps":["trace[1479596378] 'process raft request' (duration: 238.608258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.528679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.965302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:14.528815Z","caller":"traceutil/trace.go:171","msg":"trace[475647265] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"357.922562ms","start":"2026-04-16T17:08:14.170869Z","end":"2026-04-16T17:08:14.528792Z","steps":["trace[475647265] 'process raft request' (duration: 171.740935ms)","trace[475647265] 'compare' (duration: 185.799519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:14.528885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.170843Z","time spent":"358.009604ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:14.773391Z","caller":"traceutil/trace.go:171","msg":"trace[1318670415] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"599.653698ms","start":"2026-04-16T17:08:14.1737Z","end":"2026-04-16T17:08:14.773354Z","steps":["trace[1318670415] 'process raft request' (duration: 581.338953ms)","trace[1318670415] 'compare' (duration: 18.132142ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:14.773399Z","caller":"traceutil/trace.go:171","msg":"trace[1552564693] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"317.285254ms","start":"2026-04-16T17:08:14.456094Z","end":"2026-04-16T17:08:14.77338Z","steps":["trace[1552564693] 'process raft request' (duration: 317.217672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.773534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.173684Z","time spent":"599.77568ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:14.773542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.456062Z","time spent":"317.399925ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:14.773555Z","caller":"traceutil/trace.go:171","msg":"trace[170631286] transaction","detail":"{read_only:false; response_revision:24707; number_of_response:1; }","duration":"316.181493ms","start":"2026-04-16T17:08:14.457347Z","end":"2026-04-16T17:08:14.773529Z","steps":["trace[170631286] 'process raft request' (duration: 316.093261ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:14.773505Z","caller":"traceutil/trace.go:171","msg":"trace[667995326] linearizableReadLoop","detail":"{readStateIndex:25518; appliedIndex:25516; }","duration":"522.099462ms","start":"2026-04-16T17:08:14.251373Z","end":"2026-04-16T17:08:14.773472Z","steps":["trace[667995326] 'read index received' (duration: 91.254624ms)","trace[667995326] 'applied index is now lower than readState.Index' (duration: 430.806638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:14.773745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.457334Z","time spent":"316.334596ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:14.773791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.045115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:08:14.773827Z","caller":"traceutil/trace.go:171","msg":"trace[481015919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24707; }","duration":"357.124887ms","start":"2026-04-16T17:08:14.416691Z","end":"2026-04-16T17:08:14.773816Z","steps":["trace[481015919] 'agreement among raft nodes before linearized reading' (duration: 357.051335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.773857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.4653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-s88xd.18a6e4bc043fb42a\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-04-16T17:08:14.773918Z","caller":"traceutil/trace.go:171","msg":"trace[1178855292] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-s88xd.18a6e4bc043fb42a; range_end:; response_count:1; response_revision:24707; }","duration":"522.544822ms","start":"2026-04-16T17:08:14.251357Z","end":"2026-04-16T17:08:14.773902Z","steps":["trace[1178855292] 'agreement among raft nodes before linearized reading' (duration: 522.204265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.773976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.251302Z","time spent":"522.664065ms","remote":"127.0.0.1:46394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":972,"request content":"key:\"/registry/events/openstack/neutron-l3-agent-default-s88xd.18a6e4bc043fb42a\" "} {"level":"info","ts":"2026-04-16T17:08:14.774025Z","caller":"traceutil/trace.go:171","msg":"trace[447540123] transaction","detail":"{read_only:false; response_revision:24706; number_of_response:1; }","duration":"317.906136ms","start":"2026-04-16T17:08:14.456106Z","end":"2026-04-16T17:08:14.774012Z","steps":["trace[447540123] 'process raft request' (duration: 317.241803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.773868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.416666Z","time spent":"357.191509ms","remote":"127.0.0.1:46334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T17:08:14.77414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.544577ms","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-16T17:08:14.774156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.264694ms","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-16T17:08:14.774181Z","caller":"traceutil/trace.go:171","msg":"trace[332265621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24707; }","duration":"301.617178ms","start":"2026-04-16T17:08:14.472556Z","end":"2026-04-16T17:08:14.774173Z","steps":["trace[332265621] 'agreement among raft nodes before linearized reading' (duration: 301.524417ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:14.774191Z","caller":"traceutil/trace.go:171","msg":"trace[757021079] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24707; }","duration":"299.310715ms","start":"2026-04-16T17:08:14.474872Z","end":"2026-04-16T17:08:14.774183Z","steps":["trace[757021079] 'agreement among raft nodes before linearized reading' (duration: 299.189912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.774209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.472536Z","time spent":"301.666889ms","remote":"127.0.0.1:46598","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":"warn","ts":"2026-04-16T17:08:14.77409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.456076Z","time spent":"317.980797ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:14.774314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.302966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-04-16T17:08:14.774372Z","caller":"traceutil/trace.go:171","msg":"trace[1649704865] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24707; }","duration":"126.417498ms","start":"2026-04-16T17:08:14.64794Z","end":"2026-04-16T17:08:14.774358Z","steps":["trace[1649704865] 'agreement among raft nodes before linearized reading' (duration: 126.184294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.774403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.894164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-16T17:08:14.774339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.361626ms","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-16T17:08:14.774445Z","caller":"traceutil/trace.go:171","msg":"trace[1844305609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24707; }","duration":"310.935824ms","start":"2026-04-16T17:08:14.463499Z","end":"2026-04-16T17:08:14.774435Z","steps":["trace[1844305609] 'agreement among raft nodes before linearized reading' (duration: 310.831172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.774451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.937604ms","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-16T17:08:14.774472Z","caller":"traceutil/trace.go:171","msg":"trace[1605955876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24707; }","duration":"145.524959ms","start":"2026-04-16T17:08:14.62894Z","end":"2026-04-16T17:08:14.774465Z","steps":["trace[1605955876] 'agreement among raft nodes before linearized reading' (duration: 145.353116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.774479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.46346Z","time spent":"311.012995ms","remote":"127.0.0.1:46598","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-16T17:08:14.774491Z","caller":"traceutil/trace.go:171","msg":"trace[1169487380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24707; }","duration":"302.001915ms","start":"2026-04-16T17:08:14.472478Z","end":"2026-04-16T17:08:14.77448Z","steps":["trace[1169487380] 'agreement among raft nodes before linearized reading' (duration: 301.873063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:14.774556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.472468Z","time spent":"302.055796ms","remote":"127.0.0.1:46598","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":"info","ts":"2026-04-16T17:08:15.082573Z","caller":"traceutil/trace.go:171","msg":"trace[1120284117] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"304.733216ms","start":"2026-04-16T17:08:14.777808Z","end":"2026-04-16T17:08:15.082542Z","steps":["trace[1120284117] 'process raft request' (duration: 218.980166ms)","trace[1120284117] 'compare' (duration: 85.481165ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:15.082731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.777773Z","time spent":"304.88018ms","remote":"127.0.0.1:46394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":931,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:15.084894Z","caller":"traceutil/trace.go:171","msg":"trace[33187532] linearizableReadLoop","detail":"{readStateIndex:25525; appliedIndex:25521; }","duration":"305.270347ms","start":"2026-04-16T17:08:14.779607Z","end":"2026-04-16T17:08:15.084877Z","steps":["trace[33187532] 'read index received' (duration: 217.105091ms)","trace[33187532] 'applied index is now lower than readState.Index' (duration: 88.163706ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:15.085145Z","caller":"traceutil/trace.go:171","msg":"trace[704689788] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"306.480231ms","start":"2026-04-16T17:08:14.778653Z","end":"2026-04-16T17:08:15.085133Z","steps":["trace[704689788] 'process raft request' (duration: 305.967661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:15.085221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.778622Z","time spent":"306.559132ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:15.085368Z","caller":"traceutil/trace.go:171","msg":"trace[1302790160] transaction","detail":"{read_only:false; response_revision:24710; number_of_response:1; }","duration":"306.45733ms","start":"2026-04-16T17:08:14.778901Z","end":"2026-04-16T17:08:15.085358Z","steps":["trace[1302790160] 'process raft request' (duration: 305.800868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:15.085435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.778892Z","time spent":"306.49917ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:15.085616Z","caller":"traceutil/trace.go:171","msg":"trace[350346458] transaction","detail":"{read_only:false; response_revision:24711; number_of_response:1; }","duration":"305.994971ms","start":"2026-04-16T17:08:14.779564Z","end":"2026-04-16T17:08:15.085559Z","steps":["trace[350346458] 'process raft request' (duration: 305.195636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:15.085672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.779554Z","time spent":"306.087832ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:15.085817Z","caller":"traceutil/trace.go:171","msg":"trace[326592403] transaction","detail":"{read_only:false; response_revision:24712; number_of_response:1; }","duration":"306.00119ms","start":"2026-04-16T17:08:14.779804Z","end":"2026-04-16T17:08:15.085805Z","steps":["trace[326592403] 'process raft request' (duration: 305.001752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:15.085877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.779788Z","time spent":"306.059792ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:15.086005Z","caller":"traceutil/trace.go:171","msg":"trace[1947454700] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"306.080252ms","start":"2026-04-16T17:08:14.779915Z","end":"2026-04-16T17:08:15.085995Z","steps":["trace[1947454700] 'process raft request' (duration: 304.92545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:15.086065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.779907Z","time spent":"306.127782ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:15.086286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.677443ms","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-16T17:08:15.086328Z","caller":"traceutil/trace.go:171","msg":"trace[1825550028] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24713; }","duration":"306.744835ms","start":"2026-04-16T17:08:14.779573Z","end":"2026-04-16T17:08:15.086318Z","steps":["trace[1825550028] 'agreement among raft nodes before linearized reading' (duration: 306.603652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:15.086363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.779554Z","time spent":"306.802525ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-16T17:08:15.38283Z","caller":"traceutil/trace.go:171","msg":"trace[926728321] linearizableReadLoop","detail":"{readStateIndex:25528; appliedIndex:25527; }","duration":"297.853857ms","start":"2026-04-16T17:08:15.084944Z","end":"2026-04-16T17:08:15.382798Z","steps":["trace[926728321] 'read index received' (duration: 267.613565ms)","trace[926728321] 'applied index is now lower than readState.Index' (duration: 30.238401ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:15.382862Z","caller":"traceutil/trace.go:171","msg":"trace[296319338] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"382.993265ms","start":"2026-04-16T17:08:14.999835Z","end":"2026-04-16T17:08:15.382829Z","steps":["trace[296319338] 'process raft request' (duration: 352.620111ms)","trace[296319338] 'compare' (duration: 30.113929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:15.383018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.999812Z","time spent":"383.119448ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:15.383124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.568904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-04-16T17:08:15.383236Z","caller":"traceutil/trace.go:171","msg":"trace[1027346609] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24714; }","duration":"601.746637ms","start":"2026-04-16T17:08:14.781462Z","end":"2026-04-16T17:08:15.383209Z","steps":["trace[1027346609] 'agreement among raft nodes before linearized reading' (duration: 601.445052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:15.383304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:14.781448Z","time spent":"601.83937ms","remote":"127.0.0.1:46802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11739,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-04-16T17:08:15.716873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.659861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:15.717061Z","caller":"traceutil/trace.go:171","msg":"trace[1266147509] transaction","detail":"{read_only:false; response_revision:24715; number_of_response:1; }","duration":"480.882304ms","start":"2026-04-16T17:08:15.23614Z","end":"2026-04-16T17:08:15.717023Z","steps":["trace[1266147509] 'process raft request' (duration: 324.991029ms)","trace[1266147509] 'compare' (duration: 155.474717ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:15.717183Z","caller":"traceutil/trace.go:171","msg":"trace[1342124764] linearizableReadLoop","detail":"{readStateIndex:25529; appliedIndex:25528; }","duration":"334.256604ms","start":"2026-04-16T17:08:15.382907Z","end":"2026-04-16T17:08:15.717164Z","steps":["trace[1342124764] 'read index received' (duration: 178.217386ms)","trace[1342124764] 'applied index is now lower than readState.Index' (duration: 156.037298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:15.718996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:15.236108Z","time spent":"481.030976ms","remote":"127.0.0.1:46394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T17:08:15.869559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.586252ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-16T17:08:15.869844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.267365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T17:08:15.86994Z","caller":"traceutil/trace.go:171","msg":"trace[456942202] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:24715; }","duration":"676.396558ms","start":"2026-04-16T17:08:15.193518Z","end":"2026-04-16T17:08:15.869915Z","steps":["trace[456942202] 'agreement among raft nodes before linearized reading' (duration: 523.661292ms)","trace[456942202] 'count revisions from in-memory index tree' (duration: 152.558183ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:15.869996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:15.193456Z","time spent":"676.527821ms","remote":"127.0.0.1:46714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-16T17:08:15.870094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.151758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-04-16T17:08:15.87019Z","caller":"traceutil/trace.go:171","msg":"trace[1671281648] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24715; }","duration":"779.296631ms","start":"2026-04-16T17:08:15.090865Z","end":"2026-04-16T17:08:15.870161Z","steps":["trace[1671281648] 'agreement among raft nodes before linearized reading' (duration: 626.376402ms)","trace[1671281648] 'range keys from in-memory index tree' (duration: 152.641944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:15.870241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:15.09084Z","time spent":"779.391683ms","remote":"127.0.0.1:46486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18565,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-16T17:08:15.870558Z","caller":"traceutil/trace.go:171","msg":"trace[1440840293] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"332.791837ms","start":"2026-04-16T17:08:15.53775Z","end":"2026-04-16T17:08:15.870541Z","steps":["trace[1440840293] 'process raft request' (duration: 331.938911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:15.870674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:15.537726Z","time spent":"332.874798ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:16.014441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.823516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:16.014568Z","caller":"traceutil/trace.go:171","msg":"trace[1552782119] linearizableReadLoop","detail":"{readStateIndex:25532; appliedIndex:25529; }","duration":"297.215215ms","start":"2026-04-16T17:08:15.717319Z","end":"2026-04-16T17:08:16.014534Z","steps":["trace[1552782119] 'read index received' (duration: 49.421µs)","trace[1552782119] 'applied index is now lower than readState.Index' (duration: 297.164614ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:16.014571Z","caller":"traceutil/trace.go:171","msg":"trace[747102336] transaction","detail":"{read_only:false; response_revision:24717; number_of_response:1; }","duration":"475.321248ms","start":"2026-04-16T17:08:15.539217Z","end":"2026-04-16T17:08:16.014538Z","steps":["trace[747102336] 'process raft request' (duration: 331.313158ms)","trace[747102336] 'compare' (duration: 143.713124ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:16.014739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:15.539191Z","time spent":"475.460482ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:16.014791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.064019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-16T17:08:16.01475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.502058ms","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-16T17:08:16.014951Z","caller":"traceutil/trace.go:171","msg":"trace[652787399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24717; }","duration":"470.701662ms","start":"2026-04-16T17:08:15.544207Z","end":"2026-04-16T17:08:16.014908Z","steps":["trace[652787399] 'agreement among raft nodes before linearized reading' (duration: 470.444367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:16.015056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:15.544194Z","time spent":"470.847774ms","remote":"127.0.0.1:46598","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-16T17:08:16.015149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.044078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-7b8fc4b886-sjp8z.18a6e55e11d892bb\" ","response":"range_response_count:1 size:834"} {"level":"info","ts":"2026-04-16T17:08:16.014827Z","caller":"traceutil/trace.go:171","msg":"trace[1217547732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24717; }","duration":"598.095209ms","start":"2026-04-16T17:08:15.416722Z","end":"2026-04-16T17:08:16.014817Z","steps":["trace[1217547732] 'agreement among raft nodes before linearized reading' (duration: 598.048398ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:16.015303Z","caller":"traceutil/trace.go:171","msg":"trace[866291490] range","detail":"{range_begin:/registry/events/openstack/magnum-api-7b8fc4b886-sjp8z.18a6e55e11d892bb; range_end:; response_count:1; response_revision:24717; }","duration":"291.254382ms","start":"2026-04-16T17:08:15.724027Z","end":"2026-04-16T17:08:16.015281Z","steps":["trace[866291490] 'agreement among raft nodes before linearized reading' (duration: 290.929306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:16.015312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:15.416671Z","time spent":"598.633069ms","remote":"127.0.0.1:46334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T17:08:16.458617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.688948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:16.458783Z","caller":"traceutil/trace.go:171","msg":"trace[1296933450] linearizableReadLoop","detail":"{readStateIndex:25535; appliedIndex:25533; }","duration":"361.778064ms","start":"2026-04-16T17:08:16.096993Z","end":"2026-04-16T17:08:16.458771Z","steps":["trace[1296933450] 'read index received' (duration: 239.932333ms)","trace[1296933450] 'applied index is now lower than readState.Index' (duration: 121.844451ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:16.458859Z","caller":"traceutil/trace.go:171","msg":"trace[1291133670] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"435.116859ms","start":"2026-04-16T17:08:16.023734Z","end":"2026-04-16T17:08:16.458851Z","steps":["trace[1291133670] 'process raft request' (duration: 313.122655ms)","trace[1291133670] 'compare' (duration: 121.26919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:16.458893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:16.023713Z","time spent":"435.16163ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:16.459161Z","caller":"traceutil/trace.go:171","msg":"trace[289213961] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"432.554031ms","start":"2026-04-16T17:08:16.0266Z","end":"2026-04-16T17:08:16.459154Z","steps":["trace[289213961] 'process raft request' (duration: 432.091942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:16.459191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:16.026571Z","time spent":"432.603782ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:16.4593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.310374ms","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-16T17:08:16.459313Z","caller":"traceutil/trace.go:171","msg":"trace[112094243] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24720; }","duration":"362.358945ms","start":"2026-04-16T17:08:16.096949Z","end":"2026-04-16T17:08:16.459308Z","steps":["trace[112094243] 'agreement among raft nodes before linearized reading' (duration: 362.278704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:16.45933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:16.09693Z","time spent":"362.394686ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-16T17:08:16.71625Z","caller":"traceutil/trace.go:171","msg":"trace[1941170139] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"181.618601ms","start":"2026-04-16T17:08:16.534605Z","end":"2026-04-16T17:08:16.716224Z","steps":["trace[1941170139] 'process raft request' (duration: 179.912469ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:27.328652Z","caller":"traceutil/trace.go:171","msg":"trace[1705785268] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"140.536845ms","start":"2026-04-16T17:08:27.188085Z","end":"2026-04-16T17:08:27.328621Z","steps":["trace[1705785268] 'process raft request' (duration: 87.394531ms)","trace[1705785268] 'compare' (duration: 52.906519ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:27.341069Z","caller":"traceutil/trace.go:171","msg":"trace[237654095] transaction","detail":"{read_only:false; response_revision:24803; number_of_response:1; }","duration":"151.058094ms","start":"2026-04-16T17:08:27.189991Z","end":"2026-04-16T17:08:27.341049Z","steps":["trace[237654095] 'process raft request' (duration: 150.898101ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:27.341343Z","caller":"traceutil/trace.go:171","msg":"trace[635395841] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"150.715818ms","start":"2026-04-16T17:08:27.190589Z","end":"2026-04-16T17:08:27.341305Z","steps":["trace[635395841] 'process raft request' (duration: 150.422762ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:27.341379Z","caller":"traceutil/trace.go:171","msg":"trace[1779839728] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"127.858336ms","start":"2026-04-16T17:08:27.213495Z","end":"2026-04-16T17:08:27.341353Z","steps":["trace[1779839728] 'process raft request' (duration: 127.740584ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:27.547739Z","caller":"traceutil/trace.go:171","msg":"trace[837618536] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"113.627056ms","start":"2026-04-16T17:08:27.434078Z","end":"2026-04-16T17:08:27.547705Z","steps":["trace[837618536] 'process raft request' (duration: 113.341861ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:31.1965Z","caller":"traceutil/trace.go:171","msg":"trace[467794050] linearizableReadLoop","detail":"{readStateIndex:25719; appliedIndex:25718; }","duration":"201.64825ms","start":"2026-04-16T17:08:30.994832Z","end":"2026-04-16T17:08:31.19648Z","steps":["trace[467794050] 'read index received' (duration: 201.380925ms)","trace[467794050] 'applied index is now lower than readState.Index' (duration: 265.805µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:31.196586Z","caller":"traceutil/trace.go:171","msg":"trace[1920981781] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"246.955455ms","start":"2026-04-16T17:08:30.949564Z","end":"2026-04-16T17:08:31.196519Z","steps":["trace[1920981781] 'process raft request' (duration: 246.749001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:31.196665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.763542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-04-16T17:08:31.196691Z","caller":"traceutil/trace.go:171","msg":"trace[1163715581] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3; range_end:; response_count:1; response_revision:24901; }","duration":"201.915775ms","start":"2026-04-16T17:08:30.994767Z","end":"2026-04-16T17:08:31.196683Z","steps":["trace[1163715581] 'agreement among raft nodes before linearized reading' (duration: 201.791712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:31.355213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.911993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:31.355522Z","caller":"traceutil/trace.go:171","msg":"trace[751940775] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"151.213656ms","start":"2026-04-16T17:08:31.204294Z","end":"2026-04-16T17:08:31.355508Z","steps":["trace[751940775] 'process raft request' (duration: 19.935006ms)","trace[751940775] 'compare' (duration: 130.628507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:31.650625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.639165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:31.650781Z","caller":"traceutil/trace.go:171","msg":"trace[355460059] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"285.278609ms","start":"2026-04-16T17:08:31.365458Z","end":"2026-04-16T17:08:31.650736Z","steps":["trace[355460059] 'process raft request' (duration: 142.263737ms)","trace[355460059] 'compare' (duration: 142.347519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:31.988603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.414741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:31.988832Z","caller":"traceutil/trace.go:171","msg":"trace[1781978553] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"422.817578ms","start":"2026-04-16T17:08:31.565996Z","end":"2026-04-16T17:08:31.988813Z","steps":["trace[1781978553] 'process raft request' (duration: 422.772907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:31.988912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:31.565966Z","time spent":"422.912279ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:31.989264Z","caller":"traceutil/trace.go:171","msg":"trace[484226234] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"622.56346ms","start":"2026-04-16T17:08:31.366687Z","end":"2026-04-16T17:08:31.989251Z","steps":["trace[484226234] 'process raft request' (duration: 416.415855ms)","trace[484226234] 'compare' (duration: 204.906842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:31.989331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:31.366655Z","time spent":"622.645932ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:31.989476Z","caller":"traceutil/trace.go:171","msg":"trace[1743814784] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"621.723995ms","start":"2026-04-16T17:08:31.367743Z","end":"2026-04-16T17:08:31.989467Z","steps":["trace[1743814784] 'process raft request' (duration: 620.962371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:31.989537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:31.367732Z","time spent":"621.774626ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:31.99046Z","caller":"traceutil/trace.go:171","msg":"trace[273068303] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25720; }","duration":"534.69604ms","start":"2026-04-16T17:08:31.455739Z","end":"2026-04-16T17:08:31.990435Z","steps":["trace[273068303] 'read index received' (duration: 51.976271ms)","trace[273068303] 'applied index is now lower than readState.Index' (duration: 482.716449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:31.990786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.967685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T17:08:31.990909Z","caller":"traceutil/trace.go:171","msg":"trace[2116262558] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:24906; }","duration":"535.182011ms","start":"2026-04-16T17:08:31.455695Z","end":"2026-04-16T17:08:31.990877Z","steps":["trace[2116262558] 'agreement among raft nodes before linearized reading' (duration: 534.841854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:31.990984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:31.455678Z","time spent":"535.291103ms","remote":"127.0.0.1:46838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":39,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-16T17:08:32.038389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.271597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:08:32.038477Z","caller":"traceutil/trace.go:171","msg":"trace[42029418] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24907; }","duration":"277.408251ms","start":"2026-04-16T17:08:31.761045Z","end":"2026-04-16T17:08:32.038454Z","steps":["trace[42029418] 'agreement among raft nodes before linearized reading' (duration: 277.264027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:32.0385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.170398ms","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-16T17:08:32.038563Z","caller":"traceutil/trace.go:171","msg":"trace[184936119] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24907; }","duration":"573.298111ms","start":"2026-04-16T17:08:31.465247Z","end":"2026-04-16T17:08:32.038545Z","steps":["trace[184936119] 'agreement among raft nodes before linearized reading' (duration: 573.055555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:32.038597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:31.46518Z","time spent":"573.411843ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-16T17:08:32.038988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.581253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T17:08:32.039043Z","caller":"traceutil/trace.go:171","msg":"trace[925170409] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24907; }","duration":"431.685405ms","start":"2026-04-16T17:08:31.607343Z","end":"2026-04-16T17:08:32.039029Z","steps":["trace[925170409] 'agreement among raft nodes before linearized reading' (duration: 431.533682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:32.039078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:31.607318Z","time spent":"431.749786ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":98,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-16T17:08:32.03911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.654207ms","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-16T17:08:32.039162Z","caller":"traceutil/trace.go:171","msg":"trace[814169248] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24907; }","duration":"375.734868ms","start":"2026-04-16T17:08:31.663412Z","end":"2026-04-16T17:08:32.039147Z","steps":["trace[814169248] 'agreement among raft nodes before linearized reading' (duration: 375.534674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:32.039205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:31.663398Z","time spent":"375.799439ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-16T17:08:32.205788Z","caller":"traceutil/trace.go:171","msg":"trace[2067011888] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"103.351093ms","start":"2026-04-16T17:08:32.102418Z","end":"2026-04-16T17:08:32.20577Z","steps":["trace[2067011888] 'process raft request' (duration: 103.293172ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:32.205952Z","caller":"traceutil/trace.go:171","msg":"trace[957622425] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"161.48494ms","start":"2026-04-16T17:08:32.04441Z","end":"2026-04-16T17:08:32.205895Z","steps":["trace[957622425] 'process raft request' (duration: 96.164877ms)","trace[957622425] 'compare' (duration: 64.973187ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:32.206045Z","caller":"traceutil/trace.go:171","msg":"trace[503912020] linearizableReadLoop","detail":"{readStateIndex:25726; appliedIndex:25725; }","duration":"161.179955ms","start":"2026-04-16T17:08:32.044854Z","end":"2026-04-16T17:08:32.206034Z","steps":["trace[503912020] 'read index received' (duration: 95.736009ms)","trace[503912020] 'applied index is now lower than readState.Index' (duration: 65.443046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:32.206216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.354788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-04-16T17:08:32.206248Z","caller":"traceutil/trace.go:171","msg":"trace[54090958] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24909; }","duration":"161.400219ms","start":"2026-04-16T17:08:32.04484Z","end":"2026-04-16T17:08:32.20624Z","steps":["trace[54090958] 'agreement among raft nodes before linearized reading' (duration: 161.272006ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:32.620766Z","caller":"traceutil/trace.go:171","msg":"trace[833341868] linearizableReadLoop","detail":"{readStateIndex:25728; appliedIndex:25727; }","duration":"205.714506ms","start":"2026-04-16T17:08:32.415019Z","end":"2026-04-16T17:08:32.620734Z","steps":["trace[833341868] 'read index received' (duration: 178.766057ms)","trace[833341868] 'applied index is now lower than readState.Index' (duration: 26.946459ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:32.620781Z","caller":"traceutil/trace.go:171","msg":"trace[1395666971] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"273.767721ms","start":"2026-04-16T17:08:32.34697Z","end":"2026-04-16T17:08:32.620738Z","steps":["trace[1395666971] 'process raft request' (duration: 246.875914ms)","trace[1395666971] 'compare' (duration: 26.549311ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:32.620881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.848328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:08:32.620975Z","caller":"traceutil/trace.go:171","msg":"trace[1568736880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24910; }","duration":"205.991311ms","start":"2026-04-16T17:08:32.414965Z","end":"2026-04-16T17:08:32.620956Z","steps":["trace[1568736880] 'agreement among raft nodes before linearized reading' (duration: 205.864089ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:32.729614Z","caller":"traceutil/trace.go:171","msg":"trace[234432163] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"102.944625ms","start":"2026-04-16T17:08:32.626618Z","end":"2026-04-16T17:08:32.729563Z","steps":["trace[234432163] 'process raft request' (duration: 100.619851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:33.01084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.332361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:33.010991Z","caller":"traceutil/trace.go:171","msg":"trace[1197178393] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"235.190523ms","start":"2026-04-16T17:08:32.775765Z","end":"2026-04-16T17:08:33.010955Z","steps":["trace[1197178393] 'process raft request' (duration: 112.615587ms)","trace[1197178393] 'compare' (duration: 121.930403ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:33.019836Z","caller":"traceutil/trace.go:171","msg":"trace[1259442825] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"151.747376ms","start":"2026-04-16T17:08:32.868063Z","end":"2026-04-16T17:08:33.01981Z","steps":["trace[1259442825] 'process raft request' (duration: 151.654325ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:33.019861Z","caller":"traceutil/trace.go:171","msg":"trace[627330393] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"154.379117ms","start":"2026-04-16T17:08:32.865446Z","end":"2026-04-16T17:08:33.019825Z","steps":["trace[627330393] 'process raft request' (duration: 154.122632ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:33.020131Z","caller":"traceutil/trace.go:171","msg":"trace[563808837] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"138.135479ms","start":"2026-04-16T17:08:32.881981Z","end":"2026-04-16T17:08:33.020116Z","steps":["trace[563808837] 'process raft request' (duration: 137.787602ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:33.020173Z","caller":"traceutil/trace.go:171","msg":"trace[1171202040] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"138.16376ms","start":"2026-04-16T17:08:32.881982Z","end":"2026-04-16T17:08:33.020146Z","steps":["trace[1171202040] 'process raft request' (duration: 137.954816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:33.476581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.903795ms","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-16T17:08:33.476641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.990247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-04-16T17:08:33.476673Z","caller":"traceutil/trace.go:171","msg":"trace[1225281501] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3; range_end:; response_count:1; response_revision:24916; }","duration":"265.114788ms","start":"2026-04-16T17:08:33.211548Z","end":"2026-04-16T17:08:33.476662Z","steps":["trace[1225281501] 'range keys from in-memory index tree' (duration: 264.795853ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:33.476668Z","caller":"traceutil/trace.go:171","msg":"trace[2113677953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24916; }","duration":"111.033508ms","start":"2026-04-16T17:08:33.365614Z","end":"2026-04-16T17:08:33.476647Z","steps":["trace[2113677953] 'range keys from in-memory index tree' (duration: 110.663181ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:33.900663Z","caller":"traceutil/trace.go:171","msg":"trace[1369781617] linearizableReadLoop","detail":"{readStateIndex:25737; appliedIndex:25736; }","duration":"106.005332ms","start":"2026-04-16T17:08:33.79463Z","end":"2026-04-16T17:08:33.900636Z","steps":["trace[1369781617] 'read index received' (duration: 88.059574ms)","trace[1369781617] 'applied index is now lower than readState.Index' (duration: 17.944438ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:33.900761Z","caller":"traceutil/trace.go:171","msg":"trace[540702216] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"120.18741ms","start":"2026-04-16T17:08:33.780564Z","end":"2026-04-16T17:08:33.900752Z","steps":["trace[540702216] 'process raft request' (duration: 102.019968ms)","trace[540702216] 'compare' (duration: 17.750125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:33.90095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.311058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:08:33.90098Z","caller":"traceutil/trace.go:171","msg":"trace[9819298] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:24919; }","duration":"106.393579ms","start":"2026-04-16T17:08:33.794577Z","end":"2026-04-16T17:08:33.900971Z","steps":["trace[9819298] 'agreement among raft nodes before linearized reading' (duration: 106.323388ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:34.092765Z","caller":"traceutil/trace.go:171","msg":"trace[762279869] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"228.765202ms","start":"2026-04-16T17:08:33.863977Z","end":"2026-04-16T17:08:34.092742Z","steps":["trace[762279869] 'process raft request' (duration: 228.627469ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:34.09283Z","caller":"traceutil/trace.go:171","msg":"trace[559252059] linearizableReadLoop","detail":"{readStateIndex:25738; appliedIndex:25738; }","duration":"192.075929ms","start":"2026-04-16T17:08:33.900719Z","end":"2026-04-16T17:08:34.092795Z","steps":["trace[559252059] 'read index received' (duration: 192.057888ms)","trace[559252059] 'applied index is now lower than readState.Index' (duration: 15.871µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:34.093123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.489886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-04-16T17:08:34.093169Z","caller":"traceutil/trace.go:171","msg":"trace[1659273926] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24920; }","duration":"255.624628ms","start":"2026-04-16T17:08:33.837531Z","end":"2026-04-16T17:08:34.093155Z","steps":["trace[1659273926] 'agreement among raft nodes before linearized reading' (duration: 255.403564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:34.344066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.675477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:34.344255Z","caller":"traceutil/trace.go:171","msg":"trace[651323014] linearizableReadLoop","detail":"{readStateIndex:25740; appliedIndex:25738; }","duration":"251.341968ms","start":"2026-04-16T17:08:34.092897Z","end":"2026-04-16T17:08:34.344239Z","steps":["trace[651323014] 'read index received' (duration: 127.416136ms)","trace[651323014] 'applied index is now lower than readState.Index' (duration: 123.924532ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:34.344276Z","caller":"traceutil/trace.go:171","msg":"trace[304423651] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"348.519634ms","start":"2026-04-16T17:08:33.995735Z","end":"2026-04-16T17:08:34.344255Z","steps":["trace[304423651] 'process raft request' (duration: 224.563672ms)","trace[304423651] 'compare' (duration: 123.241138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:34.344362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:33.995722Z","time spent":"348.595945ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:34.344454Z","caller":"traceutil/trace.go:171","msg":"trace[378840829] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"298.181414ms","start":"2026-04-16T17:08:34.046261Z","end":"2026-04-16T17:08:34.344443Z","steps":["trace[378840829] 'process raft request' (duration: 297.906179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:34.344575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.779871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:08:34.344616Z","caller":"traceutil/trace.go:171","msg":"trace[1060341523] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24922; }","duration":"301.823262ms","start":"2026-04-16T17:08:34.042781Z","end":"2026-04-16T17:08:34.344605Z","steps":["trace[1060341523] 'agreement among raft nodes before linearized reading' (duration: 301.75729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:34.344646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:34.042711Z","time spent":"301.926513ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T17:08:34.344898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.293967ms","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-16T17:08:34.344916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.172715ms","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-16T17:08:34.344944Z","caller":"traceutil/trace.go:171","msg":"trace[746542975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24922; }","duration":"350.376789ms","start":"2026-04-16T17:08:33.994555Z","end":"2026-04-16T17:08:34.344932Z","steps":["trace[746542975] 'agreement among raft nodes before linearized reading' (duration: 350.244936ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:34.344952Z","caller":"traceutil/trace.go:171","msg":"trace[968776884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24922; }","duration":"350.224576ms","start":"2026-04-16T17:08:33.994716Z","end":"2026-04-16T17:08:34.344941Z","steps":["trace[968776884] 'agreement among raft nodes before linearized reading' (duration: 350.082313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:34.344974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:33.994536Z","time spent":"350.431139ms","remote":"127.0.0.1:46598","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-16T17:08:34.344981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:33.994709Z","time spent":"350.265927ms","remote":"127.0.0.1:46598","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":"warn","ts":"2026-04-16T17:08:34.347687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.760746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3ce524ac-e6ae-4186-95b3-53765724f192\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-04-16T17:08:34.347737Z","caller":"traceutil/trace.go:171","msg":"trace[1667063] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3ce524ac-e6ae-4186-95b3-53765724f192; range_end:; response_count:1; response_revision:24923; }","duration":"251.859188ms","start":"2026-04-16T17:08:34.095867Z","end":"2026-04-16T17:08:34.347726Z","steps":["trace[1667063] 'agreement among raft nodes before linearized reading' (duration: 251.713775ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:34.34777Z","caller":"traceutil/trace.go:171","msg":"trace[1456436814] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"136.105611ms","start":"2026-04-16T17:08:34.211643Z","end":"2026-04-16T17:08:34.347748Z","steps":["trace[1456436814] 'process raft request' (duration: 135.851856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:34.645006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.843746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:34.645101Z","caller":"traceutil/trace.go:171","msg":"trace[1215044528] linearizableReadLoop","detail":"{readStateIndex:25743; appliedIndex:25742; }","duration":"163.793594ms","start":"2026-04-16T17:08:34.48129Z","end":"2026-04-16T17:08:34.645084Z","steps":["trace[1215044528] 'read index received' (duration: 18.53981ms)","trace[1215044528] 'applied index is now lower than readState.Index' (duration: 145.252454ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:34.64514Z","caller":"traceutil/trace.go:171","msg":"trace[897861121] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"292.167009ms","start":"2026-04-16T17:08:34.352921Z","end":"2026-04-16T17:08:34.645113Z","steps":["trace[897861121] 'process raft request' (duration: 146.945916ms)","trace[897861121] 'compare' (duration: 143.730065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:34.645247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.953977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-04-16T17:08:34.64528Z","caller":"traceutil/trace.go:171","msg":"trace[1147322375] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3; range_end:; response_count:1; response_revision:24925; }","duration":"164.015698ms","start":"2026-04-16T17:08:34.481254Z","end":"2026-04-16T17:08:34.64527Z","steps":["trace[1147322375] 'agreement among raft nodes before linearized reading' (duration: 163.874886ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:35.078054Z","caller":"traceutil/trace.go:171","msg":"trace[1055592915] linearizableReadLoop","detail":"{readStateIndex:25744; appliedIndex:25743; }","duration":"318.505767ms","start":"2026-04-16T17:08:34.759506Z","end":"2026-04-16T17:08:35.078012Z","steps":["trace[1055592915] 'read index received' (duration: 318.347634ms)","trace[1055592915] 'applied index is now lower than readState.Index' (duration: 156.693µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:35.078124Z","caller":"traceutil/trace.go:171","msg":"trace[818446356] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"339.649737ms","start":"2026-04-16T17:08:34.738433Z","end":"2026-04-16T17:08:35.078083Z","steps":["trace[818446356] 'process raft request' (duration: 339.407221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:35.078294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.757742ms","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-16T17:08:35.078339Z","caller":"traceutil/trace.go:171","msg":"trace[105310051] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24926; }","duration":"318.857663ms","start":"2026-04-16T17:08:34.759468Z","end":"2026-04-16T17:08:35.078326Z","steps":["trace[105310051] 'agreement among raft nodes before linearized reading' (duration: 318.6721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:35.078383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:34.759451Z","time spent":"318.919015ms","remote":"127.0.0.1:52252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-16T17:08:35.078417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:34.738415Z","time spent":"339.904421ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:35.278084Z","caller":"traceutil/trace.go:171","msg":"trace[622988552] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"262.138282ms","start":"2026-04-16T17:08:35.015922Z","end":"2026-04-16T17:08:35.27806Z","steps":["trace[622988552] 'process raft request' (duration: 261.906008ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:35.278414Z","caller":"traceutil/trace.go:171","msg":"trace[1060652615] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"255.092139ms","start":"2026-04-16T17:08:35.023282Z","end":"2026-04-16T17:08:35.278374Z","steps":["trace[1060652615] 'process raft request' (duration: 254.736102ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:35.278706Z","caller":"traceutil/trace.go:171","msg":"trace[1075734362] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"253.554399ms","start":"2026-04-16T17:08:35.025141Z","end":"2026-04-16T17:08:35.278695Z","steps":["trace[1075734362] 'process raft request' (duration: 253.311455ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:35.278824Z","caller":"traceutil/trace.go:171","msg":"trace[1984523831] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"253.456967ms","start":"2026-04-16T17:08:35.025359Z","end":"2026-04-16T17:08:35.278816Z","steps":["trace[1984523831] 'process raft request' (duration: 253.132151ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:35.27866Z","caller":"traceutil/trace.go:171","msg":"trace[902422839] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"254.537919ms","start":"2026-04-16T17:08:35.024098Z","end":"2026-04-16T17:08:35.278636Z","steps":["trace[902422839] 'process raft request' (duration: 254.179722ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:35.46564Z","caller":"traceutil/trace.go:171","msg":"trace[660912913] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"151.832718ms","start":"2026-04-16T17:08:35.313772Z","end":"2026-04-16T17:08:35.465605Z","steps":["trace[660912913] 'process raft request' (duration: 148.268651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:35.600562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.261705ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-16T17:08:35.600655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.804002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:08:35.600757Z","caller":"traceutil/trace.go:171","msg":"trace[1849164482] linearizableReadLoop","detail":"{readStateIndex:25752; appliedIndex:25751; }","duration":"131.28436ms","start":"2026-04-16T17:08:35.46943Z","end":"2026-04-16T17:08:35.600714Z","steps":["trace[1849164482] 'read index received' (duration: 42.011µs)","trace[1849164482] 'applied index is now lower than readState.Index' (duration: 131.241029ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:35.60076Z","caller":"traceutil/trace.go:171","msg":"trace[854419238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24932; }","duration":"183.983196ms","start":"2026-04-16T17:08:35.41675Z","end":"2026-04-16T17:08:35.600734Z","steps":["trace[854419238] 'agreement among raft nodes before linearized reading' (duration: 48.921895ms)","trace[854419238] 'range keys from in-memory index tree' (duration: 134.876538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:35.600922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.471474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-7b8fc4b886-sjp8z.18a6e55e11d892bb\" ","response":"range_response_count:1 size:834"} {"level":"info","ts":"2026-04-16T17:08:35.601035Z","caller":"traceutil/trace.go:171","msg":"trace[305272243] range","detail":"{range_begin:/registry/events/openstack/magnum-api-7b8fc4b886-sjp8z.18a6e55e11d892bb; range_end:; response_count:1; response_revision:24932; }","duration":"131.635807ms","start":"2026-04-16T17:08:35.469372Z","end":"2026-04-16T17:08:35.601008Z","steps":["trace[305272243] 'agreement among raft nodes before linearized reading' (duration: 131.431993ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:37.387317Z","caller":"traceutil/trace.go:171","msg":"trace[434467140] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"102.475716ms","start":"2026-04-16T17:08:37.284811Z","end":"2026-04-16T17:08:37.387286Z","steps":["trace[434467140] 'process raft request' (duration: 102.284503ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:37.420954Z","caller":"traceutil/trace.go:171","msg":"trace[1669649612] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"136.092202ms","start":"2026-04-16T17:08:37.284834Z","end":"2026-04-16T17:08:37.420927Z","steps":["trace[1669649612] 'process raft request' (duration: 135.932529ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:37.421302Z","caller":"traceutil/trace.go:171","msg":"trace[917678439] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"136.307415ms","start":"2026-04-16T17:08:37.284954Z","end":"2026-04-16T17:08:37.421261Z","steps":["trace[917678439] 'process raft request' (duration: 135.936058ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:37.421342Z","caller":"traceutil/trace.go:171","msg":"trace[1767073870] linearizableReadLoop","detail":"{readStateIndex:25769; appliedIndex:25764; }","duration":"115.810107ms","start":"2026-04-16T17:08:37.305508Z","end":"2026-04-16T17:08:37.421318Z","steps":["trace[1767073870] 'read index received' (duration: 81.56223ms)","trace[1767073870] 'applied index is now lower than readState.Index' (duration: 34.246107ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:37.421389Z","caller":"traceutil/trace.go:171","msg":"trace[1457302079] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"136.057781ms","start":"2026-04-16T17:08:37.285319Z","end":"2026-04-16T17:08:37.421377Z","steps":["trace[1457302079] 'process raft request' (duration: 135.847247ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:37.421443Z","caller":"traceutil/trace.go:171","msg":"trace[2123781182] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"135.529029ms","start":"2026-04-16T17:08:37.285868Z","end":"2026-04-16T17:08:37.421397Z","steps":["trace[2123781182] 'process raft request' (duration: 135.402647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:37.425936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.406704ms","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-16T17:08:37.425999Z","caller":"traceutil/trace.go:171","msg":"trace[2029986458] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24949; }","duration":"120.519497ms","start":"2026-04-16T17:08:37.305465Z","end":"2026-04-16T17:08:37.425984Z","steps":["trace[2029986458] 'agreement among raft nodes before linearized reading' (duration: 116.008391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:41.328543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.987281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:08:41.328647Z","caller":"traceutil/trace.go:171","msg":"trace[589072681] linearizableReadLoop","detail":"{readStateIndex:25813; appliedIndex:25812; }","duration":"117.661602ms","start":"2026-04-16T17:08:41.21097Z","end":"2026-04-16T17:08:41.328631Z","steps":["trace[589072681] 'read index received' (duration: 1.391736ms)","trace[589072681] 'applied index is now lower than readState.Index' (duration: 116.268586ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:41.328744Z","caller":"traceutil/trace.go:171","msg":"trace[728575570] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"155.928076ms","start":"2026-04-16T17:08:41.172777Z","end":"2026-04-16T17:08:41.328705Z","steps":["trace[728575570] 'process raft request' (duration: 39.69643ms)","trace[728575570] 'compare' (duration: 115.687626ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:08:41.328795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.828915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-16T17:08:41.32883Z","caller":"traceutil/trace.go:171","msg":"trace[756429526] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24991; }","duration":"117.945478ms","start":"2026-04-16T17:08:41.210871Z","end":"2026-04-16T17:08:41.328816Z","steps":["trace[756429526] 'agreement among raft nodes before linearized reading' (duration: 117.807025ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:41.507291Z","caller":"traceutil/trace.go:171","msg":"trace[129454390] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"173.286213ms","start":"2026-04-16T17:08:41.333974Z","end":"2026-04-16T17:08:41.507261Z","steps":["trace[129454390] 'process raft request' (duration: 116.560651ms)","trace[129454390] 'compare' (duration: 56.59824ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:41.753044Z","caller":"traceutil/trace.go:171","msg":"trace[794167636] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"418.672279ms","start":"2026-04-16T17:08:41.334331Z","end":"2026-04-16T17:08:41.753003Z","steps":["trace[794167636] 'process raft request' (duration: 340.797998ms)","trace[794167636] 'compare' (duration: 77.403712ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:41.753079Z","caller":"traceutil/trace.go:171","msg":"trace[1818050413] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"417.772422ms","start":"2026-04-16T17:08:41.335277Z","end":"2026-04-16T17:08:41.75305Z","steps":["trace[1818050413] 'process raft request' (duration: 417.65792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:41.753228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.334304Z","time spent":"418.821261ms","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:41.753255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.33527Z","time spent":"417.887484ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:41.753717Z","caller":"traceutil/trace.go:171","msg":"trace[1378238493] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"155.798204ms","start":"2026-04-16T17:08:41.597895Z","end":"2026-04-16T17:08:41.753693Z","steps":["trace[1378238493] 'process raft request' (duration: 155.754143ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:41.754248Z","caller":"traceutil/trace.go:171","msg":"trace[1584482521] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"353.244033ms","start":"2026-04-16T17:08:41.400992Z","end":"2026-04-16T17:08:41.754236Z","steps":["trace[1584482521] 'process raft request' (duration: 352.320955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:41.754739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.400962Z","time spent":"353.693171ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:41.754912Z","caller":"traceutil/trace.go:171","msg":"trace[2044407246] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"322.243997ms","start":"2026-04-16T17:08:41.432656Z","end":"2026-04-16T17:08:41.7549Z","steps":["trace[2044407246] 'process raft request' (duration: 320.798759ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:41.755074Z","caller":"traceutil/trace.go:171","msg":"trace[191639270] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"322.250817ms","start":"2026-04-16T17:08:41.432769Z","end":"2026-04-16T17:08:41.75502Z","steps":["trace[191639270] 'process raft request' (duration: 320.723348ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:41.754996Z","caller":"traceutil/trace.go:171","msg":"trace[740544248] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25813; }","duration":"409.396124ms","start":"2026-04-16T17:08:41.345551Z","end":"2026-04-16T17:08:41.754947Z","steps":["trace[740544248] 'read index received' (duration: 105.002403ms)","trace[740544248] 'applied index is now lower than readState.Index' (duration: 304.387681ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:08:41.755176Z","caller":"traceutil/trace.go:171","msg":"trace[501389767] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"409.289572ms","start":"2026-04-16T17:08:41.345846Z","end":"2026-04-16T17:08:41.755136Z","steps":["trace[501389767] 'process raft request' (duration: 407.165832ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:41.755159Z","caller":"traceutil/trace.go:171","msg":"trace[1619636594] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"322.521342ms","start":"2026-04-16T17:08:41.432549Z","end":"2026-04-16T17:08:41.755071Z","steps":["trace[1619636594] 'process raft request' (duration: 320.86428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:41.755295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.72353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-04-16T17:08:41.755314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.345816Z","time spent":"409.413294ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:41.755382Z","caller":"traceutil/trace.go:171","msg":"trace[1738607569] range","detail":"{range_begin:/registry/persistentvolumes/pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3; range_end:; response_count:1; response_revision:25001; }","duration":"409.864112ms","start":"2026-04-16T17:08:41.345495Z","end":"2026-04-16T17:08:41.755359Z","steps":["trace[1738607569] 'agreement among raft nodes before linearized reading' (duration: 409.579367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:41.755381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.063595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-16T17:08:41.755437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.345471Z","time spent":"409.954124ms","remote":"127.0.0.1:46470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-aa2a3ff9-d39d-4f06-be93-cb0a987772b3\" "} {"level":"warn","ts":"2026-04-16T17:08:41.755442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.432522Z","time spent":"322.775377ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T17:08:41.755457Z","caller":"traceutil/trace.go:171","msg":"trace[1315475621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25001; }","duration":"339.197048ms","start":"2026-04-16T17:08:41.416237Z","end":"2026-04-16T17:08:41.755434Z","steps":["trace[1315475621] 'agreement among raft nodes before linearized reading' (duration: 339.069625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:41.755308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.432734Z","time spent":"322.445081ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:41.755438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.405549ms","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-16T17:08:41.75491Z","caller":"traceutil/trace.go:171","msg":"trace[374717208] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"319.510496ms","start":"2026-04-16T17:08:41.435376Z","end":"2026-04-16T17:08:41.754887Z","steps":["trace[374717208] 'process raft request' (duration: 318.155701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:08:41.755516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.416223Z","time spent":"339.279549ms","remote":"127.0.0.1:46334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T17:08:41.755933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.43536Z","time spent":"320.518894ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:41.755143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T17:08:41.43264Z","time spent":"322.464312ms","remote":"127.0.0.1:46598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T17:08:41.755431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.617229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:08:41.756705Z","caller":"traceutil/trace.go:171","msg":"trace[835855219] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25001; }","duration":"229.946584ms","start":"2026-04-16T17:08:41.526727Z","end":"2026-04-16T17:08:41.756674Z","steps":["trace[835855219] 'agreement among raft nodes before linearized reading' (duration: 228.634948ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:41.75564Z","caller":"traceutil/trace.go:171","msg":"trace[438215383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25001; }","duration":"116.614192ms","start":"2026-04-16T17:08:41.638981Z","end":"2026-04-16T17:08:41.755595Z","steps":["trace[438215383] 'agreement among raft nodes before linearized reading' (duration: 116.331327ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:08:41.862572Z","caller":"traceutil/trace.go:171","msg":"trace[194449599] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"101.361725ms","start":"2026-04-16T17:08:41.761175Z","end":"2026-04-16T17:08:41.862537Z","steps":["trace[194449599] 'process raft request' (duration: 94.463695ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:03.344357Z","caller":"traceutil/trace.go:171","msg":"trace[1343289857] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"134.100043ms","start":"2026-04-16T17:09:03.210229Z","end":"2026-04-16T17:09:03.344329Z","steps":["trace[1343289857] 'process raft request' (duration: 133.736566ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:03.800637Z","caller":"traceutil/trace.go:171","msg":"trace[152941617] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"148.562857ms","start":"2026-04-16T17:09:03.652045Z","end":"2026-04-16T17:09:03.800608Z","steps":["trace[152941617] 'process raft request' (duration: 148.364833ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:03.941104Z","caller":"traceutil/trace.go:171","msg":"trace[967616361] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"102.465636ms","start":"2026-04-16T17:09:03.838608Z","end":"2026-04-16T17:09:03.941074Z","steps":["trace[967616361] 'process raft request' (duration: 11.311964ms)","trace[967616361] 'compare' (duration: 90.764055ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:03.941287Z","caller":"traceutil/trace.go:171","msg":"trace[643767998] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"102.091769ms","start":"2026-04-16T17:09:03.839182Z","end":"2026-04-16T17:09:03.941274Z","steps":["trace[643767998] 'process raft request' (duration: 101.888545ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:03.941267Z","caller":"traceutil/trace.go:171","msg":"trace[413559693] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"102.065759ms","start":"2026-04-16T17:09:03.839166Z","end":"2026-04-16T17:09:03.941232Z","steps":["trace[413559693] 'process raft request' (duration: 101.805234ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:03.94149Z","caller":"traceutil/trace.go:171","msg":"trace[737784729] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"101.60976ms","start":"2026-04-16T17:09:03.839867Z","end":"2026-04-16T17:09:03.941477Z","steps":["trace[737784729] 'process raft request' (duration: 101.240573ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:04.414868Z","caller":"traceutil/trace.go:171","msg":"trace[456368445] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"136.58002ms","start":"2026-04-16T17:09:04.278257Z","end":"2026-04-16T17:09:04.414837Z","steps":["trace[456368445] 'process raft request' (duration: 136.404537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:04.60632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.68833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:09:04.606468Z","caller":"traceutil/trace.go:171","msg":"trace[1801113529] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"144.997438ms","start":"2026-04-16T17:09:04.461456Z","end":"2026-04-16T17:09:04.606454Z","steps":["trace[1801113529] 'process raft request' (duration: 34.093324ms)","trace[1801113529] 'compare' (duration: 110.509197ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:04.607349Z","caller":"traceutil/trace.go:171","msg":"trace[877743954] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"145.802924ms","start":"2026-04-16T17:09:04.461505Z","end":"2026-04-16T17:09:04.607308Z","steps":["trace[877743954] 'process raft request' (duration: 144.903866ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:04.607471Z","caller":"traceutil/trace.go:171","msg":"trace[934302092] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"141.395981ms","start":"2026-04-16T17:09:04.466042Z","end":"2026-04-16T17:09:04.607438Z","steps":["trace[934302092] 'process raft request' (duration: 140.881261ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:04.786496Z","caller":"traceutil/trace.go:171","msg":"trace[2081370639] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"142.032874ms","start":"2026-04-16T17:09:04.644434Z","end":"2026-04-16T17:09:04.786467Z","steps":["trace[2081370639] 'process raft request' (duration: 141.851059ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:04.970843Z","caller":"traceutil/trace.go:171","msg":"trace[1340911920] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"169.295788ms","start":"2026-04-16T17:09:04.801515Z","end":"2026-04-16T17:09:04.970811Z","steps":["trace[1340911920] 'process raft request' (duration: 99.851917ms)","trace[1340911920] 'compare' (duration: 69.305299ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:06.076762Z","caller":"traceutil/trace.go:171","msg":"trace[2138418833] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"128.384665ms","start":"2026-04-16T17:09:05.948338Z","end":"2026-04-16T17:09:06.076723Z","steps":["trace[2138418833] 'process raft request' (duration: 102.631919ms)","trace[2138418833] 'compare' (duration: 25.544152ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:06.077091Z","caller":"traceutil/trace.go:171","msg":"trace[668742816] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"128.684521ms","start":"2026-04-16T17:09:05.948381Z","end":"2026-04-16T17:09:06.077066Z","steps":["trace[668742816] 'process raft request' (duration: 128.290814ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:06.077422Z","caller":"traceutil/trace.go:171","msg":"trace[1705150022] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"128.998107ms","start":"2026-04-16T17:09:05.948411Z","end":"2026-04-16T17:09:06.077409Z","steps":["trace[1705150022] 'process raft request' (duration: 128.543008ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:06.07793Z","caller":"traceutil/trace.go:171","msg":"trace[453654656] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"127.5591ms","start":"2026-04-16T17:09:05.950358Z","end":"2026-04-16T17:09:06.077917Z","steps":["trace[453654656] 'process raft request' (duration: 127.000539ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:06.768033Z","caller":"traceutil/trace.go:171","msg":"trace[1167860840] linearizableReadLoop","detail":"{readStateIndex:26035; appliedIndex:26032; }","duration":"142.478132ms","start":"2026-04-16T17:09:06.625509Z","end":"2026-04-16T17:09:06.767987Z","steps":["trace[1167860840] 'read index received' (duration: 35.576143ms)","trace[1167860840] 'applied index is now lower than readState.Index' (duration: 106.900719ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:06.768185Z","caller":"traceutil/trace.go:171","msg":"trace[698531070] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"156.033128ms","start":"2026-04-16T17:09:06.612139Z","end":"2026-04-16T17:09:06.768172Z","steps":["trace[698531070] 'process raft request' (duration: 155.799824ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:06.768178Z","caller":"traceutil/trace.go:171","msg":"trace[150404148] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"156.369564ms","start":"2026-04-16T17:09:06.611763Z","end":"2026-04-16T17:09:06.768132Z","steps":["trace[150404148] 'process raft request' (duration: 156.014848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:06.768576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.034682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T17:09:06.768628Z","caller":"traceutil/trace.go:171","msg":"trace[1683614200] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25208; }","duration":"143.162974ms","start":"2026-04-16T17:09:06.625453Z","end":"2026-04-16T17:09:06.768616Z","steps":["trace[1683614200] 'agreement among raft nodes before linearized reading' (duration: 143.048942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:06.768844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.011555ms","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-16T17:09:06.768891Z","caller":"traceutil/trace.go:171","msg":"trace[2009615224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25208; }","duration":"131.108747ms","start":"2026-04-16T17:09:06.637765Z","end":"2026-04-16T17:09:06.768874Z","steps":["trace[2009615224] 'agreement among raft nodes before linearized reading' (duration: 130.931744ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:07.094507Z","caller":"traceutil/trace.go:171","msg":"trace[1513324146] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"114.84616ms","start":"2026-04-16T17:09:06.979634Z","end":"2026-04-16T17:09:07.09448Z","steps":["trace[1513324146] 'process raft request' (duration: 114.653656ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:08.12507Z","caller":"traceutil/trace.go:171","msg":"trace[2038472230] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"155.350744ms","start":"2026-04-16T17:09:07.969685Z","end":"2026-04-16T17:09:08.125036Z","steps":["trace[2038472230] 'process raft request' (duration: 155.13561ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:08.125123Z","caller":"traceutil/trace.go:171","msg":"trace[1317784229] linearizableReadLoop","detail":"{readStateIndex:26040; appliedIndex:26040; }","duration":"110.160981ms","start":"2026-04-16T17:09:08.014917Z","end":"2026-04-16T17:09:08.125078Z","steps":["trace[1317784229] 'read index received' (duration: 110.11664ms)","trace[1317784229] 'applied index is now lower than readState.Index' (duration: 25.931µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:09:08.125514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.574328ms","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-16T17:09:08.125739Z","caller":"traceutil/trace.go:171","msg":"trace[354711869] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25213; }","duration":"110.875535ms","start":"2026-04-16T17:09:08.014851Z","end":"2026-04-16T17:09:08.125727Z","steps":["trace[354711869] 'agreement among raft nodes before linearized reading' (duration: 110.365505ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:08.206612Z","caller":"traceutil/trace.go:171","msg":"trace[1298846097] transaction","detail":"{read_only:false; response_revision:25215; number_of_response:1; }","duration":"122.522594ms","start":"2026-04-16T17:09:08.084066Z","end":"2026-04-16T17:09:08.206589Z","steps":["trace[1298846097] 'process raft request' (duration: 122.427952ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:08.206708Z","caller":"traceutil/trace.go:171","msg":"trace[1734266579] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"122.669286ms","start":"2026-04-16T17:09:08.084004Z","end":"2026-04-16T17:09:08.206673Z","steps":["trace[1734266579] 'process raft request' (duration: 58.651878ms)","trace[1734266579] 'compare' (duration: 63.693572ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:08.206932Z","caller":"traceutil/trace.go:171","msg":"trace[1698436897] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"122.635577ms","start":"2026-04-16T17:09:08.084278Z","end":"2026-04-16T17:09:08.206913Z","steps":["trace[1698436897] 'process raft request' (duration: 122.271709ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:08.207051Z","caller":"traceutil/trace.go:171","msg":"trace[1919305724] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"111.529507ms","start":"2026-04-16T17:09:08.095512Z","end":"2026-04-16T17:09:08.207042Z","steps":["trace[1919305724] 'process raft request' (duration: 111.325963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:08.983578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.841483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-16T17:09:08.983736Z","caller":"traceutil/trace.go:171","msg":"trace[247565709] linearizableReadLoop","detail":"{readStateIndex:26049; appliedIndex:26048; }","duration":"172.713893ms","start":"2026-04-16T17:09:08.810997Z","end":"2026-04-16T17:09:08.983711Z","steps":["trace[247565709] 'read index received' (duration: 69.485222ms)","trace[247565709] 'applied index is now lower than readState.Index' (duration: 103.222231ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:08.983809Z","caller":"traceutil/trace.go:171","msg":"trace[1779941278] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"206.996381ms","start":"2026-04-16T17:09:08.776764Z","end":"2026-04-16T17:09:08.98376Z","steps":["trace[1779941278] 'process raft request' (duration: 103.818391ms)","trace[1779941278] 'compare' (duration: 102.600309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:09:08.983915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.987947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:09:08.983952Z","caller":"traceutil/trace.go:171","msg":"trace[1365319704] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25222; }","duration":"146.081099ms","start":"2026-04-16T17:09:08.837854Z","end":"2026-04-16T17:09:08.983935Z","steps":["trace[1365319704] 'agreement among raft nodes before linearized reading' (duration: 145.990087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:08.984015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.998809ms","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-16T17:09:08.984114Z","caller":"traceutil/trace.go:171","msg":"trace[1826674965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25222; }","duration":"173.161722ms","start":"2026-04-16T17:09:08.810925Z","end":"2026-04-16T17:09:08.984087Z","steps":["trace[1826674965] 'agreement among raft nodes before linearized reading' (duration: 172.911817ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:09.201241Z","caller":"traceutil/trace.go:171","msg":"trace[2009557025] linearizableReadLoop","detail":"{readStateIndex:26050; appliedIndex:26049; }","duration":"151.729456ms","start":"2026-04-16T17:09:09.049486Z","end":"2026-04-16T17:09:09.201216Z","steps":["trace[2009557025] 'read index received' (duration: 76.682369ms)","trace[2009557025] 'applied index is now lower than readState.Index' (duration: 75.046027ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:09.201499Z","caller":"traceutil/trace.go:171","msg":"trace[2144016944] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"212.27061ms","start":"2026-04-16T17:09:08.98918Z","end":"2026-04-16T17:09:09.201451Z","steps":["trace[2144016944] 'process raft request' (duration: 137.017049ms)","trace[2144016944] 'compare' (duration: 74.893644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:09:09.201554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.621439ms","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-16T17:09:09.201608Z","caller":"traceutil/trace.go:171","msg":"trace[340199795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25223; }","duration":"102.739241ms","start":"2026-04-16T17:09:09.098858Z","end":"2026-04-16T17:09:09.201597Z","steps":["trace[340199795] 'agreement among raft nodes before linearized reading' (duration: 102.569668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:09.201535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.029353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:09:09.2017Z","caller":"traceutil/trace.go:171","msg":"trace[978968424] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25223; }","duration":"152.252047ms","start":"2026-04-16T17:09:09.049411Z","end":"2026-04-16T17:09:09.201663Z","steps":["trace[978968424] 'agreement among raft nodes before linearized reading' (duration: 152.009883ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:12.283657Z","caller":"traceutil/trace.go:171","msg":"trace[1679487539] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"144.126963ms","start":"2026-04-16T17:09:12.139507Z","end":"2026-04-16T17:09:12.283634Z","steps":["trace[1679487539] 'process raft request' (duration: 143.97507ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:12.427506Z","caller":"traceutil/trace.go:171","msg":"trace[1039937136] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"205.109164ms","start":"2026-04-16T17:09:12.22238Z","end":"2026-04-16T17:09:12.427489Z","steps":["trace[1039937136] 'process raft request' (duration: 205.049773ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:12.427517Z","caller":"traceutil/trace.go:171","msg":"trace[1245266166] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"206.004841ms","start":"2026-04-16T17:09:12.22149Z","end":"2026-04-16T17:09:12.427495Z","steps":["trace[1245266166] 'process raft request' (duration: 205.811028ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:12.427688Z","caller":"traceutil/trace.go:171","msg":"trace[1137100447] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"203.7936ms","start":"2026-04-16T17:09:12.223888Z","end":"2026-04-16T17:09:12.427681Z","steps":["trace[1137100447] 'process raft request' (duration: 203.571056ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:12.427757Z","caller":"traceutil/trace.go:171","msg":"trace[1034857849] linearizableReadLoop","detail":"{readStateIndex:26076; appliedIndex:26072; }","duration":"145.935407ms","start":"2026-04-16T17:09:12.28181Z","end":"2026-04-16T17:09:12.427745Z","steps":["trace[1034857849] 'read index received' (duration: 2.326344ms)","trace[1034857849] 'applied index is now lower than readState.Index' (duration: 143.607583ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T17:09:12.427774Z","caller":"traceutil/trace.go:171","msg":"trace[504390418] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"202.058517ms","start":"2026-04-16T17:09:12.225709Z","end":"2026-04-16T17:09:12.427768Z","steps":["trace[504390418] 'process raft request' (duration: 201.939485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:12.427944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.11526ms","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-16T17:09:12.427979Z","caller":"traceutil/trace.go:171","msg":"trace[1650186295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25248; }","duration":"146.166541ms","start":"2026-04-16T17:09:12.281803Z","end":"2026-04-16T17:09:12.427969Z","steps":["trace[1650186295] 'agreement among raft nodes before linearized reading' (duration: 146.020378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:12.42805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.84365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T17:09:12.42808Z","caller":"traceutil/trace.go:171","msg":"trace[263142652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25248; }","duration":"140.896571ms","start":"2026-04-16T17:09:12.287177Z","end":"2026-04-16T17:09:12.428074Z","steps":["trace[263142652] 'agreement among raft nodes before linearized reading' (duration: 140.84988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:12.428226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.854614ms","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-16T17:09:12.428264Z","caller":"traceutil/trace.go:171","msg":"trace[1068222532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25248; }","duration":"137.904255ms","start":"2026-04-16T17:09:12.290351Z","end":"2026-04-16T17:09:12.428255Z","steps":["trace[1068222532] 'agreement among raft nodes before linearized reading' (duration: 137.808964ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:15.082005Z","caller":"traceutil/trace.go:171","msg":"trace[747570457] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"102.442766ms","start":"2026-04-16T17:09:14.97952Z","end":"2026-04-16T17:09:15.081963Z","steps":["trace[747570457] 'process raft request' (duration: 102.14685ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:16.546562Z","caller":"traceutil/trace.go:171","msg":"trace[474590951] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"105.576195ms","start":"2026-04-16T17:09:16.440952Z","end":"2026-04-16T17:09:16.546528Z","steps":["trace[474590951] 'process raft request' (duration: 105.36312ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:16.652019Z","caller":"traceutil/trace.go:171","msg":"trace[1659716601] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"206.219665ms","start":"2026-04-16T17:09:16.445771Z","end":"2026-04-16T17:09:16.651991Z","steps":["trace[1659716601] 'process raft request' (duration: 206.132864ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:16.65206Z","caller":"traceutil/trace.go:171","msg":"trace[1006791491] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"206.273776ms","start":"2026-04-16T17:09:16.445751Z","end":"2026-04-16T17:09:16.652024Z","steps":["trace[1006791491] 'process raft request' (duration: 205.97126ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:16.652258Z","caller":"traceutil/trace.go:171","msg":"trace[2067001462] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"206.139253ms","start":"2026-04-16T17:09:16.446111Z","end":"2026-04-16T17:09:16.65225Z","steps":["trace[2067001462] 'process raft request' (duration: 205.839108ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:16.652292Z","caller":"traceutil/trace.go:171","msg":"trace[1670451308] linearizableReadLoop","detail":"{readStateIndex:26106; appliedIndex:26102; }","duration":"200.566448ms","start":"2026-04-16T17:09:16.451684Z","end":"2026-04-16T17:09:16.65225Z","steps":["trace[1670451308] 'read index received' (duration: 94.670208ms)","trace[1670451308] 'applied index is now lower than readState.Index' (duration: 105.8938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T17:09:16.652633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.914175ms","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-16T17:09:16.652775Z","caller":"traceutil/trace.go:171","msg":"trace[430314355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25277; }","duration":"201.108288ms","start":"2026-04-16T17:09:16.451641Z","end":"2026-04-16T17:09:16.65275Z","steps":["trace[430314355] 'agreement among raft nodes before linearized reading' (duration: 200.752782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T17:09:16.652694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.752282ms","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-16T17:09:16.652907Z","caller":"traceutil/trace.go:171","msg":"trace[831549682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25277; }","duration":"201.016717ms","start":"2026-04-16T17:09:16.451865Z","end":"2026-04-16T17:09:16.652881Z","steps":["trace[831549682] 'agreement among raft nodes before linearized reading' (duration: 200.582069ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:19.924244Z","caller":"traceutil/trace.go:171","msg":"trace[1546793122] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"118.414197ms","start":"2026-04-16T17:09:19.805791Z","end":"2026-04-16T17:09:19.924205Z","steps":["trace[1546793122] 'process raft request' (duration: 118.090171ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:41.772066Z","caller":"traceutil/trace.go:171","msg":"trace[1513034557] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"128.891735ms","start":"2026-04-16T17:09:41.643146Z","end":"2026-04-16T17:09:41.772038Z","steps":["trace[1513034557] 'process raft request' (duration: 128.480127ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:09:42.057149Z","caller":"traceutil/trace.go:171","msg":"trace[1099910609] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"108.804975ms","start":"2026-04-16T17:09:41.948307Z","end":"2026-04-16T17:09:42.057112Z","steps":["trace[1099910609] 'process raft request' (duration: 108.588761ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T17:10:32.59774Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006940.wal"} {"level":"info","ts":"2026-04-16T17:10:47.064373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23306} {"level":"info","ts":"2026-04-16T17:10:47.198409Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23306,"took":"130.918743ms","hash":2490907748,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":35999744,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-16T17:10:47.19847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2490907748,"revision":23306,"compact-revision":20145} {"level":"info","ts":"2026-04-16T17:15:47.07183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26221} {"level":"info","ts":"2026-04-16T17:15:47.21266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26221,"took":"137.486848ms","hash":727822273,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":34127872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-16T17:15:47.212724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":727822273,"revision":26221,"compact-revision":23306} {"level":"info","ts":"2026-04-16T17:16:13.451136Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T17:16:13.456357Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-16T17:16:13.456457Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-16T17:20:47.080935Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28878} {"level":"info","ts":"2026-04-16T17:20:47.218489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28878,"took":"133.648824ms","hash":1308191887,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":30040064,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-16T17:20:47.218571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1308191887,"revision":28878,"compact-revision":26221} {"level":"info","ts":"2026-04-16T17:25:47.114044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31103} {"level":"info","ts":"2026-04-16T17:25:47.239697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31103,"took":"119.26826ms","hash":2046214396,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":31232000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-16T17:25:47.239755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2046214396,"revision":31103,"compact-revision":28878} {"level":"info","ts":"2026-04-16T17:30:47.120044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33396} {"level":"info","ts":"2026-04-16T17:30:47.268522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33396,"took":"145.576997ms","hash":3044684387,"current-db-size-bytes":59531264,"current-db-size":"60 MB","current-db-size-in-use-bytes":32477184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-16T17:30:47.268595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3044684387,"revision":33396,"compact-revision":31103} {"level":"info","ts":"2026-04-16T17:35:47.126432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35677} {"level":"info","ts":"2026-04-16T17:35:47.26517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35677,"took":"133.415567ms","hash":3171107372,"current-db-size-bytes":59531264,"current-db-size":"60 MB","current-db-size-in-use-bytes":31281152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-16T17:35:47.265244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3171107372,"revision":35677,"compact-revision":33396} {"level":"info","ts":"2026-04-16T17:37:38.817347Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T17:37:38.827353Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-16T17:37:38.827449Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}