{"level":"warn","ts":"2026-04-02T16:49:04.779027Z","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-02T16:49:04.779168Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.186: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.19.213.186:2380","--initial-cluster=instance=https://199.19.213.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.186: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-02T16:49:04.779227Z","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-02T16:49:04.779239Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-04-02T16:49:04.779257Z","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-02T16:49:04.779842Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"]} {"level":"info","ts":"2026-04-02T16:49:04.779911Z","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.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.186: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-02T16:49:04.783978Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.862253ms"} {"level":"info","ts":"2026-04-02T16:49:04.790382Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"22478416d949d47d","cluster-id":"568bb29601528c07"} {"level":"info","ts":"2026-04-02T16:49:04.790463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T16:49:04.790489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 0"} {"level":"info","ts":"2026-04-02T16:49:04.790501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 22478416d949d47d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T16:49:04.79051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 1"} {"level":"info","ts":"2026-04-02T16:49:04.790549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"warn","ts":"2026-04-02T16:49:04.795718Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T16:49:04.79708Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T16:49:04.798349Z","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-02T16:49:04.800389Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"22478416d949d47d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T16:49:04.800567Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"22478416d949d47d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T16:49:04.800802Z","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-02T16:49:04.80086Z","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-02T16:49:04.800876Z","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-02T16:49:04.80254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"info","ts":"2026-04-02T16:49:04.803445Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","added-peer-id":"22478416d949d47d","added-peer-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-04-02T16:49:04.805787Z","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-02T16:49:04.80598Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-04-02T16:49:04.80607Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-04-02T16:49:04.806216Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"22478416d949d47d","initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T16:49:04.806302Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T16:49:05.592712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T16:49:05.592776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T16:49:05.592814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgPreVoteResp from 22478416d949d47d at term 1"} {"level":"info","ts":"2026-04-02T16:49:05.592965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became candidate at term 2"} {"level":"info","ts":"2026-04-02T16:49:05.592983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgVoteResp from 22478416d949d47d at term 2"} {"level":"info","ts":"2026-04-02T16:49:05.592994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became leader at term 2"} {"level":"info","ts":"2026-04-02T16:49:05.593005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 22478416d949d47d elected leader 22478416d949d47d at term 2"} {"level":"info","ts":"2026-04-02T16:49:05.595087Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"22478416d949d47d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.186:2379]}","request-path":"/0/members/22478416d949d47d/attributes","cluster-id":"568bb29601528c07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T16:49:05.595218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:49:05.595381Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:49:05.595414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:49:05.595603Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T16:49:05.595676Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T16:49:05.596314Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:49:05.596453Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:49:05.596501Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:49:05.598625Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.186:2379"} {"level":"info","ts":"2026-04-02T16:49:05.600253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T16:49:23.441999Z","caller":"traceutil/trace.go:171","msg":"trace[647371681] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"179.859103ms","start":"2026-04-02T16:49:23.262116Z","end":"2026-04-02T16:49:23.441975Z","steps":["trace[647371681] 'process raft request' (duration: 179.70383ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:23.569192Z","caller":"traceutil/trace.go:171","msg":"trace[525232894] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"111.314333ms","start":"2026-04-02T16:49:23.457833Z","end":"2026-04-02T16:49:23.569147Z","steps":["trace[525232894] 'process raft request' (duration: 110.375435ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:34.11715Z","caller":"traceutil/trace.go:171","msg":"trace[1096965538] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"174.867739ms","start":"2026-04-02T16:49:33.942254Z","end":"2026-04-02T16:49:34.117122Z","steps":["trace[1096965538] 'process raft request' (duration: 167.331257ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:34.11797Z","caller":"traceutil/trace.go:171","msg":"trace[1435333413] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"166.957949ms","start":"2026-04-02T16:49:33.950979Z","end":"2026-04-02T16:49:34.117937Z","steps":["trace[1435333413] 'process raft request' (duration: 166.561922ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:34.118166Z","caller":"traceutil/trace.go:171","msg":"trace[1116829585] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"164.84086ms","start":"2026-04-02T16:49:33.953304Z","end":"2026-04-02T16:49:34.118145Z","steps":["trace[1116829585] 'process raft request' (duration: 164.559425ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:34.118603Z","caller":"traceutil/trace.go:171","msg":"trace[1191087952] transaction","detail":"{read_only:false; number_of_response:1; response_revision:560; }","duration":"145.162339ms","start":"2026-04-02T16:49:33.973415Z","end":"2026-04-02T16:49:34.118577Z","steps":["trace[1191087952] 'process raft request' (duration: 144.807183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:39.718967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.207258ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:49:39.7192Z","caller":"traceutil/trace.go:171","msg":"trace[2088354914] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:615; }","duration":"205.473363ms","start":"2026-04-02T16:49:39.513694Z","end":"2026-04-02T16:49:39.719167Z","steps":["trace[2088354914] 'range keys from in-memory index tree' (duration: 205.166018ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:39.7345Z","caller":"traceutil/trace.go:171","msg":"trace[1373679578] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"229.486546ms","start":"2026-04-02T16:49:39.504967Z","end":"2026-04-02T16:49:39.734454Z","steps":["trace[1373679578] 'process raft request' (duration: 228.157741ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:39.982725Z","caller":"traceutil/trace.go:171","msg":"trace[810006458] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"191.764986ms","start":"2026-04-02T16:49:39.790932Z","end":"2026-04-02T16:49:39.982697Z","steps":["trace[810006458] 'process raft request' (duration: 191.625232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:40.646497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.312407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hxvvq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-02T16:49:40.646565Z","caller":"traceutil/trace.go:171","msg":"trace[1406217842] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hxvvq; range_end:; response_count:1; response_revision:619; }","duration":"292.435969ms","start":"2026-04-02T16:49:40.354112Z","end":"2026-04-02T16:49:40.646548Z","steps":["trace[1406217842] 'range keys from in-memory index tree' (duration: 292.197184ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:40.647989Z","caller":"traceutil/trace.go:171","msg":"trace[338685615] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"134.441868ms","start":"2026-04-02T16:49:40.51352Z","end":"2026-04-02T16:49:40.647961Z","steps":["trace[338685615] 'read index received' (duration: 133.50729ms)","trace[338685615] 'applied index is now lower than readState.Index' (duration: 933.728µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:49:40.648067Z","caller":"traceutil/trace.go:171","msg":"trace[1955121734] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"273.605035ms","start":"2026-04-02T16:49:40.374435Z","end":"2026-04-02T16:49:40.64804Z","steps":["trace[1955121734] 'process raft request' (duration: 272.882641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:40.648087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.57266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:49:40.648224Z","caller":"traceutil/trace.go:171","msg":"trace[1586240690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"134.751283ms","start":"2026-04-02T16:49:40.513461Z","end":"2026-04-02T16:49:40.648212Z","steps":["trace[1586240690] 'agreement among raft nodes before linearized reading' (duration: 134.592351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:40.648283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.054798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T16:49:40.648368Z","caller":"traceutil/trace.go:171","msg":"trace[1470573124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"111.200861ms","start":"2026-04-02T16:49:40.537155Z","end":"2026-04-02T16:49:40.648356Z","steps":["trace[1470573124] 'agreement among raft nodes before linearized reading' (duration: 110.943936ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:56.29743Z","caller":"traceutil/trace.go:171","msg":"trace[1096138672] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"155.880153ms","start":"2026-04-02T16:49:56.141527Z","end":"2026-04-02T16:49:56.297407Z","steps":["trace[1096138672] 'process raft request' (duration: 155.761071ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:51:13.740385Z","caller":"traceutil/trace.go:171","msg":"trace[1168590923] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"152.79733ms","start":"2026-04-02T16:51:13.587561Z","end":"2026-04-02T16:51:13.740358Z","steps":["trace[1168590923] 'process raft request' (duration: 152.627357ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:52:02.530195Z","caller":"traceutil/trace.go:171","msg":"trace[1785197662] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"106.398679ms","start":"2026-04-02T16:52:02.423773Z","end":"2026-04-02T16:52:02.530171Z","steps":["trace[1785197662] 'process raft request' (duration: 106.284987ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:52:02.545083Z","caller":"traceutil/trace.go:171","msg":"trace[1588827875] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"116.784685ms","start":"2026-04-02T16:52:02.428262Z","end":"2026-04-02T16:52:02.545047Z","steps":["trace[1588827875] 'process raft request' (duration: 116.50708ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:52:08.167175Z","caller":"traceutil/trace.go:171","msg":"trace[1173693211] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"123.109005ms","start":"2026-04-02T16:52:08.044028Z","end":"2026-04-02T16:52:08.167137Z","steps":["trace[1173693211] 'process raft request' (duration: 122.935892ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:59:05.631227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2805} {"level":"info","ts":"2026-04-02T16:59:05.71027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2805,"took":"78.121124ms","hash":4188021855,"current-db-size-bytes":33341440,"current-db-size":"33 MB","current-db-size-in-use-bytes":8314880,"current-db-size-in-use":"8.3 MB"} {"level":"info","ts":"2026-04-02T16:59:05.710367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4188021855,"revision":2805,"compact-revision":-1} {"level":"warn","ts":"2026-04-02T17:02:20.699551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.202431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:02:20.699718Z","caller":"traceutil/trace.go:171","msg":"trace[428564946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5317; }","duration":"185.377345ms","start":"2026-04-02T17:02:20.514289Z","end":"2026-04-02T17:02:20.699667Z","steps":["trace[428564946] 'range keys from in-memory index tree' (duration: 185.105999ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:21.297457Z","caller":"traceutil/trace.go:171","msg":"trace[1216984429] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"116.69738ms","start":"2026-04-02T17:02:21.180735Z","end":"2026-04-02T17:02:21.297432Z","steps":["trace[1216984429] 'process raft request' (duration: 116.547358ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:21.298437Z","caller":"traceutil/trace.go:171","msg":"trace[726874799] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"115.291796ms","start":"2026-04-02T17:02:21.18311Z","end":"2026-04-02T17:02:21.298402Z","steps":["trace[726874799] 'process raft request' (duration: 115.112252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:21.448603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.987618ms","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-02T17:02:21.448706Z","caller":"traceutil/trace.go:171","msg":"trace[254946377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5320; }","duration":"116.227672ms","start":"2026-04-02T17:02:21.332462Z","end":"2026-04-02T17:02:21.44869Z","steps":["trace[254946377] 'range keys from in-memory index tree' (duration: 115.835816ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:21.704528Z","caller":"traceutil/trace.go:171","msg":"trace[1421042528] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5532; }","duration":"202.897122ms","start":"2026-04-02T17:02:21.501587Z","end":"2026-04-02T17:02:21.704484Z","steps":["trace[1421042528] 'read index received' (duration: 202.557396ms)","trace[1421042528] 'applied index is now lower than readState.Index' (duration: 338.276µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:21.704557Z","caller":"traceutil/trace.go:171","msg":"trace[352690564] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"251.098782ms","start":"2026-04-02T17:02:21.453414Z","end":"2026-04-02T17:02:21.704513Z","steps":["trace[352690564] 'process raft request' (duration: 250.874938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:21.704731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.134896ms","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-02T17:02:21.704807Z","caller":"traceutil/trace.go:171","msg":"trace[754439902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5321; }","duration":"203.218068ms","start":"2026-04-02T17:02:21.501548Z","end":"2026-04-02T17:02:21.704766Z","steps":["trace[754439902] 'agreement among raft nodes before linearized reading' (duration: 203.062995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:21.704896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.240244ms","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-02T17:02:21.704984Z","caller":"traceutil/trace.go:171","msg":"trace[663715696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5321; }","duration":"199.333065ms","start":"2026-04-02T17:02:21.505629Z","end":"2026-04-02T17:02:21.704962Z","steps":["trace[663715696] 'agreement among raft nodes before linearized reading' (duration: 199.121872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:22.062585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.312075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:22.062698Z","caller":"traceutil/trace.go:171","msg":"trace[702378401] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"352.241053ms","start":"2026-04-02T17:02:21.710441Z","end":"2026-04-02T17:02:22.062682Z","steps":["trace[702378401] 'process raft request' (duration: 129.213365ms)","trace[702378401] 'compare' (duration: 222.215033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:22.062756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:21.710429Z","time spent":"352.295774ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:22.062981Z","caller":"traceutil/trace.go:171","msg":"trace[1103572468] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5533; }","duration":"124.68873ms","start":"2026-04-02T17:02:21.938264Z","end":"2026-04-02T17:02:22.062953Z","steps":["trace[1103572468] 'read index received' (duration: 33.501126ms)","trace[1103572468] 'applied index is now lower than readState.Index' (duration: 91.186274ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:22.0631Z","caller":"traceutil/trace.go:171","msg":"trace[1428318027] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"352.346436ms","start":"2026-04-02T17:02:21.710742Z","end":"2026-04-02T17:02:22.063088Z","steps":["trace[1428318027] 'process raft request' (duration: 351.921258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:22.063207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:21.710718Z","time spent":"352.413126ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:22.063386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.088397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T17:02:22.063496Z","caller":"traceutil/trace.go:171","msg":"trace[24670597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5323; }","duration":"125.23779ms","start":"2026-04-02T17:02:21.938235Z","end":"2026-04-02T17:02:22.063472Z","steps":["trace[24670597] 'agreement among raft nodes before linearized reading' (duration: 124.930965ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:22.127201Z","caller":"traceutil/trace.go:171","msg":"trace[1836452628] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"132.954635ms","start":"2026-04-02T17:02:21.994225Z","end":"2026-04-02T17:02:22.127179Z","steps":["trace[1836452628] 'process raft request' (duration: 132.794292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:22.12725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.481107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:02:22.127321Z","caller":"traceutil/trace.go:171","msg":"trace[650007555] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5324; }","duration":"147.587009ms","start":"2026-04-02T17:02:21.979717Z","end":"2026-04-02T17:02:22.127304Z","steps":["trace[650007555] 'agreement among raft nodes before linearized reading' (duration: 147.445226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:22.12741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.670058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T17:02:22.127516Z","caller":"traceutil/trace.go:171","msg":"trace[1699783388] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5324; }","duration":"186.853192ms","start":"2026-04-02T17:02:21.940635Z","end":"2026-04-02T17:02:22.127488Z","steps":["trace[1699783388] 'agreement among raft nodes before linearized reading' (duration: 186.565097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:22.383378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.352695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:22.383584Z","caller":"traceutil/trace.go:171","msg":"trace[1450787193] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"250.121075ms","start":"2026-04-02T17:02:22.133444Z","end":"2026-04-02T17:02:22.383565Z","steps":["trace[1450787193] 'process raft request' (duration: 250.058054ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:22.383578Z","caller":"traceutil/trace.go:171","msg":"trace[1403097606] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"315.435015ms","start":"2026-04-02T17:02:22.068109Z","end":"2026-04-02T17:02:22.383544Z","steps":["trace[1403097606] 'process raft request' (duration: 168.803384ms)","trace[1403097606] 'compare' (duration: 146.213873ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:22.383672Z","caller":"traceutil/trace.go:171","msg":"trace[2105183211] linearizableReadLoop","detail":"{readStateIndex:5537; appliedIndex:5536; }","duration":"256.491223ms","start":"2026-04-02T17:02:22.127142Z","end":"2026-04-02T17:02:22.383633Z","steps":["trace[2105183211] 'read index received' (duration: 109.908343ms)","trace[2105183211] 'applied index is now lower than readState.Index' (duration: 146.57927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:22.383713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:22.068081Z","time spent":"315.559897ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:22.383846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.344779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T17:02:22.383893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.48665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T17:02:22.383914Z","caller":"traceutil/trace.go:171","msg":"trace[1230683684] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5326; }","duration":"296.448571ms","start":"2026-04-02T17:02:22.087446Z","end":"2026-04-02T17:02:22.383895Z","steps":["trace[1230683684] 'agreement among raft nodes before linearized reading' (duration: 296.302218ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:22.383928Z","caller":"traceutil/trace.go:171","msg":"trace[450577133] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5326; }","duration":"312.643942ms","start":"2026-04-02T17:02:22.071271Z","end":"2026-04-02T17:02:22.383915Z","steps":["trace[450577133] 'agreement among raft nodes before linearized reading' (duration: 312.485119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:22.383969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:22.071234Z","time spent":"312.728964ms","remote":"127.0.0.1:53378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-02T17:02:22.718248Z","caller":"traceutil/trace.go:171","msg":"trace[1995064514] linearizableReadLoop","detail":"{readStateIndex:5539; appliedIndex:5538; }","duration":"205.835487ms","start":"2026-04-02T17:02:22.512384Z","end":"2026-04-02T17:02:22.71822Z","steps":["trace[1995064514] 'read index received' (duration: 205.630754ms)","trace[1995064514] 'applied index is now lower than readState.Index' (duration: 203.473µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:22.718288Z","caller":"traceutil/trace.go:171","msg":"trace[886236922] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"276.733162ms","start":"2026-04-02T17:02:22.441517Z","end":"2026-04-02T17:02:22.71825Z","steps":["trace[886236922] 'process raft request' (duration: 276.512028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:22.718419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.01673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:02:22.718463Z","caller":"traceutil/trace.go:171","msg":"trace[1970995623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5327; }","duration":"206.125103ms","start":"2026-04-02T17:02:22.512327Z","end":"2026-04-02T17:02:22.718452Z","steps":["trace[1970995623] 'agreement among raft nodes before linearized reading' (duration: 206.00473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:23.659488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.675532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:23.659696Z","caller":"traceutil/trace.go:171","msg":"trace[2134936735] linearizableReadLoop","detail":"{readStateIndex:5542; appliedIndex:5540; }","duration":"269.211831ms","start":"2026-04-02T17:02:23.390468Z","end":"2026-04-02T17:02:23.65968Z","steps":["trace[2134936735] 'read index received' (duration: 115.213233ms)","trace[2134936735] 'applied index is now lower than readState.Index' (duration: 153.997568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:23.659796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.750822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:02:23.659832Z","caller":"traceutil/trace.go:171","msg":"trace[1973138074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5330; }","duration":"146.811004ms","start":"2026-04-02T17:02:23.513012Z","end":"2026-04-02T17:02:23.659823Z","steps":["trace[1973138074] 'agreement among raft nodes before linearized reading' (duration: 146.759303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:23.659933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.438705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T17:02:23.659986Z","caller":"traceutil/trace.go:171","msg":"trace[828494494] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"356.132646ms","start":"2026-04-02T17:02:23.303811Z","end":"2026-04-02T17:02:23.659944Z","steps":["trace[828494494] 'process raft request' (duration: 201.919874ms)","trace[828494494] 'compare' (duration: 153.423428ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:23.660057Z","caller":"traceutil/trace.go:171","msg":"trace[331657588] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5330; }","duration":"269.64673ms","start":"2026-04-02T17:02:23.39038Z","end":"2026-04-02T17:02:23.660026Z","steps":["trace[331657588] 'agreement among raft nodes before linearized reading' (duration: 269.346214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:23.660192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:23.303788Z","time spent":"356.295689ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:23.660753Z","caller":"traceutil/trace.go:171","msg":"trace[925501110] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"354.981103ms","start":"2026-04-02T17:02:23.304719Z","end":"2026-04-02T17:02:23.6597Z","steps":["trace[925501110] 'process raft request' (duration: 354.871281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:23.66087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:23.304694Z","time spent":"356.106395ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:24.389331Z","caller":"traceutil/trace.go:171","msg":"trace[1414636127] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"319.065841ms","start":"2026-04-02T17:02:24.070241Z","end":"2026-04-02T17:02:24.389307Z","steps":["trace[1414636127] 'process raft request' (duration: 318.886148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:24.389537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:24.070222Z","time spent":"319.250146ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:24.402845Z","caller":"traceutil/trace.go:171","msg":"trace[1194221049] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"332.35478ms","start":"2026-04-02T17:02:24.070461Z","end":"2026-04-02T17:02:24.402815Z","steps":["trace[1194221049] 'process raft request' (duration: 332.149617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:24.403028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:24.070442Z","time spent":"332.484703ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:24.805576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.195291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:24.805996Z","caller":"traceutil/trace.go:171","msg":"trace[860299394] linearizableReadLoop","detail":"{readStateIndex:5548; appliedIndex:5546; }","duration":"292.480476ms","start":"2026-04-02T17:02:24.513495Z","end":"2026-04-02T17:02:24.805976Z","steps":["trace[860299394] 'read index received' (duration: 29.879458ms)","trace[860299394] 'applied index is now lower than readState.Index' (duration: 262.599738ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:24.806097Z","caller":"traceutil/trace.go:171","msg":"trace[148575998] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"398.687102ms","start":"2026-04-02T17:02:24.407372Z","end":"2026-04-02T17:02:24.806059Z","steps":["trace[148575998] 'process raft request' (duration: 398.325055ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:24.806129Z","caller":"traceutil/trace.go:171","msg":"trace[24669656] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"399.106669ms","start":"2026-04-02T17:02:24.406989Z","end":"2026-04-02T17:02:24.806096Z","steps":["trace[24669656] 'process raft request' (duration: 136.287227ms)","trace[24669656] 'compare' (duration: 262.034748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:24.806194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.68214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:02:24.806281Z","caller":"traceutil/trace.go:171","msg":"trace[1136381357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5335; }","duration":"292.823862ms","start":"2026-04-02T17:02:24.513433Z","end":"2026-04-02T17:02:24.806257Z","steps":["trace[1136381357] 'agreement among raft nodes before linearized reading' (duration: 292.661109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:24.806298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:24.407354Z","time spent":"398.837234ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:24.806532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:24.406966Z","time spent":"399.221001ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:24.806616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.918537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T17:02:24.806668Z","caller":"traceutil/trace.go:171","msg":"trace[2099285866] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5335; }","duration":"139.003197ms","start":"2026-04-02T17:02:24.667644Z","end":"2026-04-02T17:02:24.806648Z","steps":["trace[2099285866] 'agreement among raft nodes before linearized reading' (duration: 138.465968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:25.111847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.862532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:25.112142Z","caller":"traceutil/trace.go:171","msg":"trace[2055026842] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"301.367513ms","start":"2026-04-02T17:02:24.810742Z","end":"2026-04-02T17:02:25.11211Z","steps":["trace[2055026842] 'process raft request' (duration: 145.101182ms)","trace[2055026842] 'compare' (duration: 155.649409ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:25.112291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:24.810719Z","time spent":"301.491884ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:25.439395Z","caller":"traceutil/trace.go:171","msg":"trace[1453084212] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"174.735286ms","start":"2026-04-02T17:02:25.264273Z","end":"2026-04-02T17:02:25.439009Z","steps":["trace[1453084212] 'process raft request' (duration: 174.535532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:25.738624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.868876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:02:25.738743Z","caller":"traceutil/trace.go:171","msg":"trace[1614103355] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5339; }","duration":"108.05945ms","start":"2026-04-02T17:02:25.630653Z","end":"2026-04-02T17:02:25.738713Z","steps":["trace[1614103355] 'count revisions from in-memory index tree' (duration: 107.754924ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:26.021401Z","caller":"traceutil/trace.go:171","msg":"trace[1223350645] linearizableReadLoop","detail":"{readStateIndex:5554; appliedIndex:5553; }","duration":"206.675893ms","start":"2026-04-02T17:02:25.81469Z","end":"2026-04-02T17:02:26.021366Z","steps":["trace[1223350645] 'read index received' (duration: 180.80995ms)","trace[1223350645] 'applied index is now lower than readState.Index' (duration: 25.864423ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:26.021451Z","caller":"traceutil/trace.go:171","msg":"trace[2135608085] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"355.162997ms","start":"2026-04-02T17:02:25.666265Z","end":"2026-04-02T17:02:26.021428Z","steps":["trace[2135608085] 'process raft request' (duration: 329.304095ms)","trace[2135608085] 'compare' (duration: 25.594787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:26.021685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.986288ms","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-02T17:02:26.021684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:25.66625Z","time spent":"355.25289ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:26.021691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.851593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:02:26.021731Z","caller":"traceutil/trace.go:171","msg":"trace[1552615172] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5341; }","duration":"207.11319ms","start":"2026-04-02T17:02:25.814606Z","end":"2026-04-02T17:02:26.021719Z","steps":["trace[1552615172] 'agreement among raft nodes before linearized reading' (duration: 206.919117ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:26.021759Z","caller":"traceutil/trace.go:171","msg":"trace[212613466] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5341; }","duration":"131.961376ms","start":"2026-04-02T17:02:25.889778Z","end":"2026-04-02T17:02:26.021739Z","steps":["trace[212613466] 'agreement among raft nodes before linearized reading' (duration: 131.857644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:26.022137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.348819ms","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-02T17:02:26.022172Z","caller":"traceutil/trace.go:171","msg":"trace[413751527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5341; }","duration":"204.383719ms","start":"2026-04-02T17:02:25.81778Z","end":"2026-04-02T17:02:26.022164Z","steps":["trace[413751527] 'agreement among raft nodes before linearized reading' (duration: 203.820128ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:50.244915Z","caller":"traceutil/trace.go:171","msg":"trace[78719993] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"128.598114ms","start":"2026-04-02T17:02:50.116294Z","end":"2026-04-02T17:02:50.244892Z","steps":["trace[78719993] 'process raft request' (duration: 128.303939ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:53.062335Z","caller":"traceutil/trace.go:171","msg":"trace[652317872] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"111.28817ms","start":"2026-04-02T17:02:52.951019Z","end":"2026-04-02T17:02:53.062307Z","steps":["trace[652317872] 'process raft request' (duration: 111.143247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:54.169717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.478435ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-02T17:02:54.215389Z","caller":"traceutil/trace.go:171","msg":"trace[708616504] linearizableReadLoop","detail":"{readStateIndex:5702; appliedIndex:5701; }","duration":"166.231607ms","start":"2026-04-02T17:02:54.049136Z","end":"2026-04-02T17:02:54.215367Z","steps":["trace[708616504] 'read index received' (duration: 166.121355ms)","trace[708616504] 'applied index is now lower than readState.Index' (duration: 109.282µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:54.215474Z","caller":"traceutil/trace.go:171","msg":"trace[199424859] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"363.617406ms","start":"2026-04-02T17:02:53.85182Z","end":"2026-04-02T17:02:54.215437Z","steps":["trace[199424859] 'process raft request' (duration: 363.429323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:54.215542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.403441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12500"} {"level":"info","ts":"2026-04-02T17:02:54.215563Z","caller":"traceutil/trace.go:171","msg":"trace[897680365] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5483; }","duration":"166.498362ms","start":"2026-04-02T17:02:54.049058Z","end":"2026-04-02T17:02:54.215556Z","steps":["trace[897680365] 'agreement among raft nodes before linearized reading' (duration: 166.37098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:54.21576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:53.851787Z","time spent":"363.807689ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:54.45283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.315101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:54.452942Z","caller":"traceutil/trace.go:171","msg":"trace[288665243] linearizableReadLoop","detail":"{readStateIndex:5703; appliedIndex:5702; }","duration":"164.079256ms","start":"2026-04-02T17:02:54.288842Z","end":"2026-04-02T17:02:54.452922Z","steps":["trace[288665243] 'read index received' (duration: 22.51345ms)","trace[288665243] 'applied index is now lower than readState.Index' (duration: 141.564206ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:54.453003Z","caller":"traceutil/trace.go:171","msg":"trace[7451488] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"194.912503ms","start":"2026-04-02T17:02:54.258063Z","end":"2026-04-02T17:02:54.452976Z","steps":["trace[7451488] 'process raft request' (duration: 53.345077ms)","trace[7451488] 'compare' (duration: 141.067777ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:54.453123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.286641ms","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-02T17:02:54.453209Z","caller":"traceutil/trace.go:171","msg":"trace[1200403553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5484; }","duration":"164.325052ms","start":"2026-04-02T17:02:54.288826Z","end":"2026-04-02T17:02:54.453151Z","steps":["trace[1200403553] 'agreement among raft nodes before linearized reading' (duration: 164.184958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:54.453311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.985161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T17:02:54.453664Z","caller":"traceutil/trace.go:171","msg":"trace[1830694587] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5484; }","duration":"144.154154ms","start":"2026-04-02T17:02:54.30925Z","end":"2026-04-02T17:02:54.453404Z","steps":["trace[1830694587] 'agreement among raft nodes before linearized reading' (duration: 143.863319ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:54.598401Z","caller":"traceutil/trace.go:171","msg":"trace[1539538722] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"140.21641ms","start":"2026-04-02T17:02:54.458158Z","end":"2026-04-02T17:02:54.598374Z","steps":["trace[1539538722] 'process raft request' (duration: 127.35716ms)","trace[1539538722] 'compare' (duration: 12.642286ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:55.363437Z","caller":"traceutil/trace.go:171","msg":"trace[862031906] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"256.166298ms","start":"2026-04-02T17:02:55.107221Z","end":"2026-04-02T17:02:55.363387Z","steps":["trace[862031906] 'process raft request' (duration: 255.941343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:55.771645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.355651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:55.771846Z","caller":"traceutil/trace.go:171","msg":"trace[1392489982] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"133.182779ms","start":"2026-04-02T17:02:55.638639Z","end":"2026-04-02T17:02:55.771822Z","steps":["trace[1392489982] 'process raft request' (duration: 133.118028ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:55.771813Z","caller":"traceutil/trace.go:171","msg":"trace[631073529] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"386.31564ms","start":"2026-04-02T17:02:55.385453Z","end":"2026-04-02T17:02:55.771769Z","steps":["trace[631073529] 'process raft request' (duration: 129.740994ms)","trace[631073529] 'compare' (duration: 256.206839ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:55.771856Z","caller":"traceutil/trace.go:171","msg":"trace[180842949] linearizableReadLoop","detail":"{readStateIndex:5710; appliedIndex:5709; }","duration":"310.009194ms","start":"2026-04-02T17:02:55.461816Z","end":"2026-04-02T17:02:55.771825Z","steps":["trace[180842949] 'read index received' (duration: 53.269176ms)","trace[180842949] 'applied index is now lower than readState.Index' (duration: 256.737567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:55.772074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.269707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:02:55.772091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:55.38543Z","time spent":"386.500473ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:55.77212Z","caller":"traceutil/trace.go:171","msg":"trace[1042290302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5492; }","duration":"259.389189ms","start":"2026-04-02T17:02:55.51272Z","end":"2026-04-02T17:02:55.772109Z","steps":["trace[1042290302] 'agreement among raft nodes before linearized reading' (duration: 259.300267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:55.772298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.448742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T17:02:55.772433Z","caller":"traceutil/trace.go:171","msg":"trace[1489644769] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5492; }","duration":"310.636296ms","start":"2026-04-02T17:02:55.461756Z","end":"2026-04-02T17:02:55.772393Z","steps":["trace[1489644769] 'agreement among raft nodes before linearized reading' (duration: 310.233958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:55.772501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:55.46173Z","time spent":"310.754978ms","remote":"127.0.0.1:53378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-02T17:02:56.356975Z","caller":"traceutil/trace.go:171","msg":"trace[376998927] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"135.870009ms","start":"2026-04-02T17:02:56.221074Z","end":"2026-04-02T17:02:56.356944Z","steps":["trace[376998927] 'process raft request' (duration: 135.647755ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:56.724222Z","caller":"traceutil/trace.go:171","msg":"trace[986286460] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"498.744052ms","start":"2026-04-02T17:02:56.225449Z","end":"2026-04-02T17:02:56.724193Z","steps":["trace[986286460] 'process raft request' (duration: 498.538037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:56.724395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:56.225419Z","time spent":"498.885105ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:56.73126Z","caller":"traceutil/trace.go:171","msg":"trace[942356596] linearizableReadLoop","detail":"{readStateIndex:5714; appliedIndex:5712; }","duration":"214.275384ms","start":"2026-04-02T17:02:56.516953Z","end":"2026-04-02T17:02:56.731228Z","steps":["trace[942356596] 'read index received' (duration: 207.12321ms)","trace[942356596] 'applied index is now lower than readState.Index' (duration: 7.150494ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:56.731385Z","caller":"traceutil/trace.go:171","msg":"trace[1897920307] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"271.007705ms","start":"2026-04-02T17:02:56.460348Z","end":"2026-04-02T17:02:56.731355Z","steps":["trace[1897920307] 'process raft request' (duration: 270.703068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:56.731462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.490738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:02:56.731505Z","caller":"traceutil/trace.go:171","msg":"trace[623970148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5495; }","duration":"214.60165ms","start":"2026-04-02T17:02:56.516891Z","end":"2026-04-02T17:02:56.731493Z","steps":["trace[623970148] 'agreement among raft nodes before linearized reading' (duration: 214.503248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:56.731813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.972461ms","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-02T17:02:56.731927Z","caller":"traceutil/trace.go:171","msg":"trace[1822882297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5495; }","duration":"128.135795ms","start":"2026-04-02T17:02:56.603769Z","end":"2026-04-02T17:02:56.731904Z","steps":["trace[1822882297] 'agreement among raft nodes before linearized reading' (duration: 127.727187ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:56.927316Z","caller":"traceutil/trace.go:171","msg":"trace[361791635] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"191.131232ms","start":"2026-04-02T17:02:56.736154Z","end":"2026-04-02T17:02:56.927285Z","steps":["trace[361791635] 'process raft request' (duration: 142.156607ms)","trace[361791635] 'compare' (duration: 48.770241ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:56.927635Z","caller":"traceutil/trace.go:171","msg":"trace[943551214] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"191.320016ms","start":"2026-04-02T17:02:56.736281Z","end":"2026-04-02T17:02:56.927601Z","steps":["trace[943551214] 'process raft request' (duration: 190.96027ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:56.927701Z","caller":"traceutil/trace.go:171","msg":"trace[168240415] linearizableReadLoop","detail":"{readStateIndex:5717; appliedIndex:5714; }","duration":"147.728931ms","start":"2026-04-02T17:02:56.779949Z","end":"2026-04-02T17:02:56.927678Z","steps":["trace[168240415] 'read index received' (duration: 98.309658ms)","trace[168240415] 'applied index is now lower than readState.Index' (duration: 49.417583ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:56.927859Z","caller":"traceutil/trace.go:171","msg":"trace[433406146] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"190.297326ms","start":"2026-04-02T17:02:56.737531Z","end":"2026-04-02T17:02:56.927828Z","steps":["trace[433406146] 'process raft request' (duration: 189.96259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:56.927995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.019926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T17:02:56.928103Z","caller":"traceutil/trace.go:171","msg":"trace[2052780951] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5498; }","duration":"148.144439ms","start":"2026-04-02T17:02:56.779877Z","end":"2026-04-02T17:02:56.928022Z","steps":["trace[2052780951] 'agreement among raft nodes before linearized reading' (duration: 147.917094ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:57.534074Z","caller":"traceutil/trace.go:171","msg":"trace[830834853] linearizableReadLoop","detail":"{readStateIndex:5718; appliedIndex:5717; }","duration":"165.536074ms","start":"2026-04-02T17:02:57.368508Z","end":"2026-04-02T17:02:57.534044Z","steps":["trace[830834853] 'read index received' (duration: 165.34838ms)","trace[830834853] 'applied index is now lower than readState.Index' (duration: 186.344µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:57.534165Z","caller":"traceutil/trace.go:171","msg":"trace[940422586] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"337.946005ms","start":"2026-04-02T17:02:57.196182Z","end":"2026-04-02T17:02:57.534128Z","steps":["trace[940422586] 'process raft request' (duration: 337.63023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:57.534408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.67804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-02T17:02:57.534439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:57.196142Z","time spent":"338.150349ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:57.534412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.87335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T17:02:57.534545Z","caller":"traceutil/trace.go:171","msg":"trace[957869356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5499; }","duration":"166.054803ms","start":"2026-04-02T17:02:57.368457Z","end":"2026-04-02T17:02:57.534512Z","steps":["trace[957869356] 'agreement among raft nodes before linearized reading' (duration: 165.718527ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:57.534463Z","caller":"traceutil/trace.go:171","msg":"trace[30570499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5499; }","duration":"155.783821ms","start":"2026-04-02T17:02:57.378663Z","end":"2026-04-02T17:02:57.534447Z","steps":["trace[30570499] 'agreement among raft nodes before linearized reading' (duration: 155.562017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:57.815138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.037476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:57.815302Z","caller":"traceutil/trace.go:171","msg":"trace[2122000219] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"275.042651ms","start":"2026-04-02T17:02:57.540231Z","end":"2026-04-02T17:02:57.815274Z","steps":["trace[2122000219] 'process raft request' (duration: 140.72644ms)","trace[2122000219] 'compare' (duration: 133.730189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:58.168836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.667412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T17:02:58.168836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.514433ms","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-02T17:02:58.168898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.57868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:02:58.168953Z","caller":"traceutil/trace.go:171","msg":"trace[994208662] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5502; }","duration":"252.909407ms","start":"2026-04-02T17:02:57.916014Z","end":"2026-04-02T17:02:58.168923Z","steps":["trace[994208662] 'count revisions from in-memory index tree' (duration: 252.56519ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:58.168962Z","caller":"traceutil/trace.go:171","msg":"trace[1353685279] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5502; }","duration":"233.682797ms","start":"2026-04-02T17:02:57.935255Z","end":"2026-04-02T17:02:58.168937Z","steps":["trace[1353685279] 'range keys from in-memory index tree' (duration: 233.29269ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:58.169002Z","caller":"traceutil/trace.go:171","msg":"trace[656032506] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:5502; }","duration":"130.724932ms","start":"2026-04-02T17:02:58.038247Z","end":"2026-04-02T17:02:58.168972Z","steps":["trace[656032506] 'count revisions from in-memory index tree' (duration: 130.498209ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:04:05.638248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4330} {"level":"info","ts":"2026-04-02T17:04:05.679138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4330,"took":"39.658199ms","hash":2639332658,"current-db-size-bytes":33341440,"current-db-size":"33 MB","current-db-size-in-use-bytes":8171520,"current-db-size-in-use":"8.2 MB"} {"level":"info","ts":"2026-04-02T17:04:05.67921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639332658,"revision":4330,"compact-revision":2805} {"level":"warn","ts":"2026-04-02T17:04:54.119062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.924446ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-02T17:04:54.371114Z","caller":"traceutil/trace.go:171","msg":"trace[1626186107] transaction","detail":"{read_only:false; response_revision:6315; number_of_response:1; }","duration":"150.59174ms","start":"2026-04-02T17:04:54.220487Z","end":"2026-04-02T17:04:54.371079Z","steps":["trace[1626186107] 'process raft request' (duration: 150.368316ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:04:54.371736Z","caller":"traceutil/trace.go:171","msg":"trace[1312494906] transaction","detail":"{read_only:false; response_revision:6316; number_of_response:1; }","duration":"122.847112ms","start":"2026-04-02T17:04:54.248855Z","end":"2026-04-02T17:04:54.371702Z","steps":["trace[1312494906] 'process raft request' (duration: 122.642747ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:08:46.866416Z","caller":"traceutil/trace.go:171","msg":"trace[1139622261] transaction","detail":"{read_only:false; response_revision:8202; number_of_response:1; }","duration":"101.514688ms","start":"2026-04-02T17:08:46.764871Z","end":"2026-04-02T17:08:46.866385Z","steps":["trace[1139622261] 'process raft request' (duration: 101.363545ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:08:55.467261Z","caller":"traceutil/trace.go:171","msg":"trace[914769770] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"110.547247ms","start":"2026-04-02T17:08:55.356682Z","end":"2026-04-02T17:08:55.467229Z","steps":["trace[914769770] 'process raft request' (duration: 110.061538ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:09:05.64357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5870} {"level":"info","ts":"2026-04-02T17:09:05.683476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5870,"took":"38.393747ms","hash":74893658,"current-db-size-bytes":33341440,"current-db-size":"33 MB","current-db-size-in-use-bytes":29851648,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-02T17:09:05.683549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":74893658,"revision":5870,"compact-revision":4330} {"level":"warn","ts":"2026-04-02T17:09:15.144991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.937555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-02T17:09:15.145106Z","caller":"traceutil/trace.go:171","msg":"trace[1639637855] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8907; }","duration":"119.112378ms","start":"2026-04-02T17:09:15.025971Z","end":"2026-04-02T17:09:15.145083Z","steps":["trace[1639637855] 'range keys from in-memory index tree' (duration: 118.76454ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:09:15.371003Z","caller":"traceutil/trace.go:171","msg":"trace[1740265102] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"220.657737ms","start":"2026-04-02T17:09:15.150309Z","end":"2026-04-02T17:09:15.370966Z","steps":["trace[1740265102] 'process raft request' (duration: 220.416403ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:09:15.397091Z","caller":"traceutil/trace.go:171","msg":"trace[550028291] transaction","detail":"{read_only:false; response_revision:8911; number_of_response:1; }","duration":"246.508501ms","start":"2026-04-02T17:09:15.150553Z","end":"2026-04-02T17:09:15.397061Z","steps":["trace[550028291] 'process raft request' (duration: 246.408208ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:09:15.39712Z","caller":"traceutil/trace.go:171","msg":"trace[1319114382] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"246.614903ms","start":"2026-04-02T17:09:15.150477Z","end":"2026-04-02T17:09:15.397092Z","steps":["trace[1319114382] 'process raft request' (duration: 246.322877ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:09:15.397439Z","caller":"traceutil/trace.go:171","msg":"trace[1525712569] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"246.648043ms","start":"2026-04-02T17:09:15.150775Z","end":"2026-04-02T17:09:15.397423Z","steps":["trace[1525712569] 'process raft request' (duration: 246.233366ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:09:15.397503Z","caller":"traceutil/trace.go:171","msg":"trace[346867671] linearizableReadLoop","detail":"{readStateIndex:9260; appliedIndex:9255; }","duration":"231.337937ms","start":"2026-04-02T17:09:15.166119Z","end":"2026-04-02T17:09:15.397457Z","steps":["trace[346867671] 'read index received' (duration: 204.734029ms)","trace[346867671] 'applied index is now lower than readState.Index' (duration: 26.601458ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:09:15.397552Z","caller":"traceutil/trace.go:171","msg":"trace[336483900] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"245.072394ms","start":"2026-04-02T17:09:15.152439Z","end":"2026-04-02T17:09:15.397512Z","steps":["trace[336483900] 'process raft request' (duration: 244.822159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:09:15.397765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.611251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-069f8300-9e4d-4604-9876-0ba206566cff\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-02T17:09:15.3979Z","caller":"traceutil/trace.go:171","msg":"trace[722474245] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-069f8300-9e4d-4604-9876-0ba206566cff; range_end:; response_count:1; response_revision:8913; }","duration":"231.773944ms","start":"2026-04-02T17:09:15.166084Z","end":"2026-04-02T17:09:15.397858Z","steps":["trace[722474245] 'agreement among raft nodes before linearized reading' (duration: 231.498519ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:10:34.429145Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T17:10:34.435251Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T17:10:34.435392Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T17:14:05.651736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8700} {"level":"info","ts":"2026-04-02T17:14:05.737279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8700,"took":"83.674562ms","hash":3421883238,"current-db-size-bytes":41660416,"current-db-size":"42 MB","current-db-size-in-use-bytes":28676096,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-02T17:14:05.737363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3421883238,"revision":8700,"compact-revision":5870} {"level":"info","ts":"2026-04-02T17:15:13.864369Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002f26.wal"} {"level":"info","ts":"2026-04-02T17:15:17.913542Z","caller":"traceutil/trace.go:171","msg":"trace[1876526548] transaction","detail":"{read_only:false; response_revision:11729; number_of_response:1; }","duration":"226.902692ms","start":"2026-04-02T17:15:17.68662Z","end":"2026-04-02T17:15:17.913523Z","steps":["trace[1876526548] 'process raft request' (duration: 226.76631ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:15:17.955239Z","caller":"traceutil/trace.go:171","msg":"trace[279281084] transaction","detail":"{read_only:false; response_revision:11730; number_of_response:1; }","duration":"121.630813ms","start":"2026-04-02T17:15:17.833574Z","end":"2026-04-02T17:15:17.955205Z","steps":["trace[279281084] 'process raft request' (duration: 121.185495ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:15:29.677194Z","caller":"traceutil/trace.go:171","msg":"trace[1814688337] transaction","detail":"{read_only:false; response_revision:11785; number_of_response:1; }","duration":"246.328095ms","start":"2026-04-02T17:15:29.430827Z","end":"2026-04-02T17:15:29.677155Z","steps":["trace[1814688337] 'process raft request' (duration: 246.148872ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:15:29.677917Z","caller":"traceutil/trace.go:171","msg":"trace[59126860] linearizableReadLoop","detail":"{readStateIndex:12223; appliedIndex:12223; }","duration":"242.989623ms","start":"2026-04-02T17:15:29.434912Z","end":"2026-04-02T17:15:29.677902Z","steps":["trace[59126860] 'read index received' (duration: 242.984023ms)","trace[59126860] 'applied index is now lower than readState.Index' (duration: 4.59µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:15:29.678215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.562084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T17:15:29.678242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.314638ms","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-02T17:15:29.678326Z","caller":"traceutil/trace.go:171","msg":"trace[21404608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11785; }","duration":"243.428761ms","start":"2026-04-02T17:15:29.434878Z","end":"2026-04-02T17:15:29.678307Z","steps":["trace[21404608] 'agreement among raft nodes before linearized reading' (duration: 243.185157ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:15:29.678335Z","caller":"traceutil/trace.go:171","msg":"trace[2042868497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11785; }","duration":"242.693756ms","start":"2026-04-02T17:15:29.435574Z","end":"2026-04-02T17:15:29.678268Z","steps":["trace[2042868497] 'agreement among raft nodes before linearized reading' (duration: 242.459202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:15:29.67863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.024633ms","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-02T17:15:29.678729Z","caller":"traceutil/trace.go:171","msg":"trace[716289158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11785; }","duration":"212.139715ms","start":"2026-04-02T17:15:29.46657Z","end":"2026-04-02T17:15:29.678709Z","steps":["trace[716289158] 'agreement among raft nodes before linearized reading' (duration: 211.968552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:15:59.195254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.258142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:15:59.19544Z","caller":"traceutil/trace.go:171","msg":"trace[1222214571] transaction","detail":"{read_only:false; response_revision:11999; number_of_response:1; }","duration":"427.583113ms","start":"2026-04-02T17:15:58.767833Z","end":"2026-04-02T17:15:59.195416Z","steps":["trace[1222214571] 'process raft request' (duration: 303.011955ms)","trace[1222214571] 'compare' (duration: 124.042698ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:15:59.195517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:15:58.76781Z","time spent":"427.673776ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:16:02.259636Z","caller":"traceutil/trace.go:171","msg":"trace[82668278] transaction","detail":"{read_only:false; response_revision:12020; number_of_response:1; }","duration":"132.143629ms","start":"2026-04-02T17:16:02.127466Z","end":"2026-04-02T17:16:02.259609Z","steps":["trace[82668278] 'process raft request' (duration: 131.715892ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:16:12.03444Z","caller":"traceutil/trace.go:171","msg":"trace[160194331] linearizableReadLoop","detail":"{readStateIndex:12558; appliedIndex:12557; }","duration":"127.207457ms","start":"2026-04-02T17:16:11.907208Z","end":"2026-04-02T17:16:12.034415Z","steps":["trace[160194331] 'read index received' (duration: 127.111785ms)","trace[160194331] 'applied index is now lower than readState.Index' (duration: 94.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:16:12.034518Z","caller":"traceutil/trace.go:171","msg":"trace[1463226491] transaction","detail":"{read_only:false; response_revision:12107; number_of_response:1; }","duration":"130.237624ms","start":"2026-04-02T17:16:11.904256Z","end":"2026-04-02T17:16:12.034493Z","steps":["trace[1463226491] 'process raft request' (duration: 130.01638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:16:12.034766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.530024ms","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-02T17:16:12.034843Z","caller":"traceutil/trace.go:171","msg":"trace[772504648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12107; }","duration":"127.626305ms","start":"2026-04-02T17:16:11.907201Z","end":"2026-04-02T17:16:12.034827Z","steps":["trace[772504648] 'agreement among raft nodes before linearized reading' (duration: 127.32959ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:16:12.143813Z","caller":"traceutil/trace.go:171","msg":"trace[1406345698] transaction","detail":"{read_only:false; response_revision:12108; number_of_response:1; }","duration":"232.976336ms","start":"2026-04-02T17:16:11.910801Z","end":"2026-04-02T17:16:12.143778Z","steps":["trace[1406345698] 'process raft request' (duration: 232.68221ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:16:12.143879Z","caller":"traceutil/trace.go:171","msg":"trace[25007698] transaction","detail":"{read_only:false; response_revision:12109; number_of_response:1; }","duration":"136.105775ms","start":"2026-04-02T17:16:12.007739Z","end":"2026-04-02T17:16:12.143845Z","steps":["trace[25007698] 'process raft request' (duration: 135.964872ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:16:12.143808Z","caller":"traceutil/trace.go:171","msg":"trace[1465149660] linearizableReadLoop","detail":"{readStateIndex:12560; appliedIndex:12558; }","duration":"109.260932ms","start":"2026-04-02T17:16:12.034519Z","end":"2026-04-02T17:16:12.14378Z","steps":["trace[1465149660] 'read index received' (duration: 109.006947ms)","trace[1465149660] 'applied index is now lower than readState.Index' (duration: 252.585µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:16:12.144097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.116969ms","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-02T17:16:12.144155Z","caller":"traceutil/trace.go:171","msg":"trace[1834521271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12109; }","duration":"232.255452ms","start":"2026-04-02T17:16:11.911888Z","end":"2026-04-02T17:16:12.144143Z","steps":["trace[1834521271] 'agreement among raft nodes before linearized reading' (duration: 231.988867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:16:12.14436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.128259ms","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-02T17:16:12.144421Z","caller":"traceutil/trace.go:171","msg":"trace[1297513171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12109; }","duration":"224.212341ms","start":"2026-04-02T17:16:11.920193Z","end":"2026-04-02T17:16:12.144405Z","steps":["trace[1297513171] 'agreement among raft nodes before linearized reading' (duration: 224.017178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:16:12.144373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.967661ms","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-02T17:16:12.144526Z","caller":"traceutil/trace.go:171","msg":"trace[1325670526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12109; }","duration":"220.148505ms","start":"2026-04-02T17:16:11.924353Z","end":"2026-04-02T17:16:12.144502Z","steps":["trace[1325670526] 'agreement among raft nodes before linearized reading' (duration: 219.764438ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:16:12.26769Z","caller":"traceutil/trace.go:171","msg":"trace[1521048653] transaction","detail":"{read_only:false; response_revision:12110; number_of_response:1; }","duration":"228.819507ms","start":"2026-04-02T17:16:12.038828Z","end":"2026-04-02T17:16:12.267647Z","steps":["trace[1521048653] 'process raft request' (duration: 208.487827ms)","trace[1521048653] 'compare' (duration: 20.055295ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:16:12.282414Z","caller":"traceutil/trace.go:171","msg":"trace[6545098] linearizableReadLoop","detail":"{readStateIndex:12564; appliedIndex:12560; }","duration":"112.463553ms","start":"2026-04-02T17:16:12.169925Z","end":"2026-04-02T17:16:12.282389Z","steps":["trace[6545098] 'read index received' (duration: 77.385217ms)","trace[6545098] 'applied index is now lower than readState.Index' (duration: 35.077506ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:16:12.282529Z","caller":"traceutil/trace.go:171","msg":"trace[754310625] transaction","detail":"{read_only:false; response_revision:12111; number_of_response:1; }","duration":"133.495386ms","start":"2026-04-02T17:16:12.149002Z","end":"2026-04-02T17:16:12.282498Z","steps":["trace[754310625] 'process raft request' (duration: 133.138099ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:16:12.282535Z","caller":"traceutil/trace.go:171","msg":"trace[1621225694] transaction","detail":"{read_only:false; response_revision:12113; number_of_response:1; }","duration":"133.412624ms","start":"2026-04-02T17:16:12.14908Z","end":"2026-04-02T17:16:12.282493Z","steps":["trace[1621225694] 'process raft request' (duration: 133.266072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:16:12.282605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.617206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:16:12.282563Z","caller":"traceutil/trace.go:171","msg":"trace[195496694] transaction","detail":"{read_only:false; response_revision:12112; number_of_response:1; }","duration":"133.473575ms","start":"2026-04-02T17:16:12.149028Z","end":"2026-04-02T17:16:12.282502Z","steps":["trace[195496694] 'process raft request' (duration: 133.279791ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:16:12.282723Z","caller":"traceutil/trace.go:171","msg":"trace[203710282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12113; }","duration":"112.766588ms","start":"2026-04-02T17:16:12.169881Z","end":"2026-04-02T17:16:12.282648Z","steps":["trace[203710282] 'agreement among raft nodes before linearized reading' (duration: 112.614085ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:17:11.666569Z","caller":"traceutil/trace.go:171","msg":"trace[952086365] transaction","detail":"{read_only:false; response_revision:12746; number_of_response:1; }","duration":"127.439873ms","start":"2026-04-02T17:17:11.539091Z","end":"2026-04-02T17:17:11.666531Z","steps":["trace[952086365] 'process raft request' (duration: 108.218483ms)","trace[952086365] 'compare' (duration: 19.050337ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:17:11.6681Z","caller":"traceutil/trace.go:171","msg":"trace[92093053] linearizableReadLoop","detail":"{readStateIndex:13215; appliedIndex:13213; }","duration":"114.252146ms","start":"2026-04-02T17:17:11.553817Z","end":"2026-04-02T17:17:11.668069Z","steps":["trace[92093053] 'read index received' (duration: 93.5959ms)","trace[92093053] 'applied index is now lower than readState.Index' (duration: 20.654916ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:17:11.668222Z","caller":"traceutil/trace.go:171","msg":"trace[111577970] transaction","detail":"{read_only:false; response_revision:12747; number_of_response:1; }","duration":"116.351805ms","start":"2026-04-02T17:17:11.551812Z","end":"2026-04-02T17:17:11.668164Z","steps":["trace[111577970] 'process raft request' (duration: 116.09631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:17:11.668262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.420069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:17:11.668805Z","caller":"traceutil/trace.go:171","msg":"trace[1096631843] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:12747; }","duration":"115.01817ms","start":"2026-04-02T17:17:11.553764Z","end":"2026-04-02T17:17:11.668782Z","steps":["trace[1096631843] 'agreement among raft nodes before linearized reading' (duration: 114.428429ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:19:05.665963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11117} {"level":"info","ts":"2026-04-02T17:19:05.751788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11117,"took":"81.754664ms","hash":1926148880,"current-db-size-bytes":41660416,"current-db-size":"42 MB","current-db-size-in-use-bytes":25747456,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-02T17:19:05.751871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1926148880,"revision":11117,"compact-revision":8700} {"level":"info","ts":"2026-04-02T17:21:08.208558Z","caller":"traceutil/trace.go:171","msg":"trace[1036123431] linearizableReadLoop","detail":"{readStateIndex:15211; appliedIndex:15210; }","duration":"225.125168ms","start":"2026-04-02T17:21:07.983407Z","end":"2026-04-02T17:21:08.208532Z","steps":["trace[1036123431] 'read index received' (duration: 224.893024ms)","trace[1036123431] 'applied index is now lower than readState.Index' (duration: 230.674µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:21:08.208616Z","caller":"traceutil/trace.go:171","msg":"trace[1312033479] transaction","detail":"{read_only:false; response_revision:14685; number_of_response:1; }","duration":"225.69948ms","start":"2026-04-02T17:21:07.982876Z","end":"2026-04-02T17:21:08.208575Z","steps":["trace[1312033479] 'process raft request' (duration: 225.458376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:21:08.208911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.454615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-02T17:21:08.209015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.844348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:21:08.209031Z","caller":"traceutil/trace.go:171","msg":"trace[1044424526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14685; }","duration":"225.62148ms","start":"2026-04-02T17:21:07.983382Z","end":"2026-04-02T17:21:08.209004Z","steps":["trace[1044424526] 'agreement among raft nodes before linearized reading' (duration: 225.305763ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:21:08.209051Z","caller":"traceutil/trace.go:171","msg":"trace[572716220] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:14685; }","duration":"184.900479ms","start":"2026-04-02T17:21:08.024136Z","end":"2026-04-02T17:21:08.209037Z","steps":["trace[572716220] 'agreement among raft nodes before linearized reading' (duration: 184.855298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:21:08.209023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.6055ms","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-02T17:21:08.209134Z","caller":"traceutil/trace.go:171","msg":"trace[618816016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14685; }","duration":"224.745302ms","start":"2026-04-02T17:21:07.984363Z","end":"2026-04-02T17:21:08.209108Z","steps":["trace[618816016] 'agreement among raft nodes before linearized reading' (duration: 224.418766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:21:08.208963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.330627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:21:08.209139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.484131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-04-02T17:21:08.209215Z","caller":"traceutil/trace.go:171","msg":"trace[1999140368] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:14685; }","duration":"185.650553ms","start":"2026-04-02T17:21:08.023554Z","end":"2026-04-02T17:21:08.209204Z","steps":["trace[1999140368] 'agreement among raft nodes before linearized reading' (duration: 185.327607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:21:08.209283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.617502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:21:08.209331Z","caller":"traceutil/trace.go:171","msg":"trace[1471018434] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:14685; }","duration":"185.687484ms","start":"2026-04-02T17:21:08.023633Z","end":"2026-04-02T17:21:08.209321Z","steps":["trace[1471018434] 'agreement among raft nodes before linearized reading' (duration: 185.620873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:21:08.209373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.362453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T17:21:08.20942Z","caller":"traceutil/trace.go:171","msg":"trace[1595973915] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14685; }","duration":"203.423864ms","start":"2026-04-02T17:21:08.005979Z","end":"2026-04-02T17:21:08.209403Z","steps":["trace[1595973915] 'agreement among raft nodes before linearized reading' (duration: 203.320552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:21:08.209442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.789865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:21:08.209471Z","caller":"traceutil/trace.go:171","msg":"trace[1784937555] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:14685; }","duration":"185.817436ms","start":"2026-04-02T17:21:08.023644Z","end":"2026-04-02T17:21:08.209461Z","steps":["trace[1784937555] 'agreement among raft nodes before linearized reading' (duration: 185.773275ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:21:08.209273Z","caller":"traceutil/trace.go:171","msg":"trace[220111795] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:14685; }","duration":"184.649794ms","start":"2026-04-02T17:21:08.024589Z","end":"2026-04-02T17:21:08.209239Z","steps":["trace[220111795] 'agreement among raft nodes before linearized reading' (duration: 184.276747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:21:08.209576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.013174ms","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-02T17:21:08.209612Z","caller":"traceutil/trace.go:171","msg":"trace[1997353081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14685; }","duration":"220.089005ms","start":"2026-04-02T17:21:07.989513Z","end":"2026-04-02T17:21:08.209602Z","steps":["trace[1997353081] 'agreement among raft nodes before linearized reading' (duration: 219.991634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:21:08.209801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.196738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T17:21:08.209928Z","caller":"traceutil/trace.go:171","msg":"trace[1486352312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14685; }","duration":"219.359551ms","start":"2026-04-02T17:21:07.990537Z","end":"2026-04-02T17:21:08.209897Z","steps":["trace[1486352312] 'agreement among raft nodes before linearized reading' (duration: 218.931053ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:21:08.350545Z","caller":"traceutil/trace.go:171","msg":"trace[824055156] transaction","detail":"{read_only:false; response_revision:14687; number_of_response:1; }","duration":"135.011039ms","start":"2026-04-02T17:21:08.215496Z","end":"2026-04-02T17:21:08.350507Z","steps":["trace[824055156] 'process raft request' (duration: 134.667752ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:21:08.350838Z","caller":"traceutil/trace.go:171","msg":"trace[298719717] transaction","detail":"{read_only:false; response_revision:14688; number_of_response:1; }","duration":"135.057109ms","start":"2026-04-02T17:21:08.215745Z","end":"2026-04-02T17:21:08.350802Z","steps":["trace[298719717] 'process raft request' (duration: 134.661582ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:21:08.350925Z","caller":"traceutil/trace.go:171","msg":"trace[204351585] transaction","detail":"{read_only:false; response_revision:14690; number_of_response:1; }","duration":"134.731213ms","start":"2026-04-02T17:21:08.216172Z","end":"2026-04-02T17:21:08.350903Z","steps":["trace[204351585] 'process raft request' (duration: 134.619951ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:21:08.350914Z","caller":"traceutil/trace.go:171","msg":"trace[1768123589] transaction","detail":"{read_only:false; response_revision:14689; number_of_response:1; }","duration":"135.11213ms","start":"2026-04-02T17:21:08.215767Z","end":"2026-04-02T17:21:08.350879Z","steps":["trace[1768123589] 'process raft request' (duration: 134.900567ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:24:05.672709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13556} {"level":"info","ts":"2026-04-02T17:24:05.753674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13556,"took":"78.006521ms","hash":1577937427,"current-db-size-bytes":41660416,"current-db-size":"42 MB","current-db-size-in-use-bytes":27930624,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-02T17:24:05.75376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1577937427,"revision":13556,"compact-revision":11117} {"level":"info","ts":"2026-04-02T17:27:02.225395Z","caller":"traceutil/trace.go:171","msg":"trace[1362905352] transaction","detail":"{read_only:false; response_revision:17099; number_of_response:1; }","duration":"135.065413ms","start":"2026-04-02T17:27:02.090297Z","end":"2026-04-02T17:27:02.225363Z","steps":["trace[1362905352] 'process raft request' (duration: 49.720456ms)","trace[1362905352] 'compare' (duration: 85.215435ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:27:02.226612Z","caller":"traceutil/trace.go:171","msg":"trace[671259237] transaction","detail":"{read_only:false; response_revision:17100; number_of_response:1; }","duration":"125.472835ms","start":"2026-04-02T17:27:02.101108Z","end":"2026-04-02T17:27:02.226581Z","steps":["trace[671259237] 'process raft request' (duration: 125.340652ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:27:37.294712Z","caller":"traceutil/trace.go:171","msg":"trace[1987634171] transaction","detail":"{read_only:false; response_revision:17299; number_of_response:1; }","duration":"111.811821ms","start":"2026-04-02T17:27:37.182871Z","end":"2026-04-02T17:27:37.294683Z","steps":["trace[1987634171] 'process raft request' (duration: 111.632618ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:27:37.603257Z","caller":"traceutil/trace.go:171","msg":"trace[1130518156] transaction","detail":"{read_only:false; response_revision:17300; number_of_response:1; }","duration":"112.248739ms","start":"2026-04-02T17:27:37.490905Z","end":"2026-04-02T17:27:37.603153Z","steps":["trace[1130518156] 'process raft request' (duration: 111.847132ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:28:00.001232Z","caller":"traceutil/trace.go:171","msg":"trace[1573902692] linearizableReadLoop","detail":"{readStateIndex:18246; appliedIndex:18245; }","duration":"264.637214ms","start":"2026-04-02T17:27:59.736563Z","end":"2026-04-02T17:28:00.0012Z","steps":["trace[1573902692] 'read index received' (duration: 264.40316ms)","trace[1573902692] 'applied index is now lower than readState.Index' (duration: 232.644µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:28:00.00134Z","caller":"traceutil/trace.go:171","msg":"trace[684293550] transaction","detail":"{read_only:false; response_revision:17613; number_of_response:1; }","duration":"294.269005ms","start":"2026-04-02T17:27:59.706989Z","end":"2026-04-02T17:28:00.001258Z","steps":["trace[684293550] 'process raft request' (duration: 293.99229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:28:00.001514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.941229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c3963ea1-ad09-494a-bdc1-394048480ca1\" ","response":"range_response_count:1 size:3640"} {"level":"info","ts":"2026-04-02T17:28:00.001568Z","caller":"traceutil/trace.go:171","msg":"trace[608020013] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c3963ea1-ad09-494a-bdc1-394048480ca1; range_end:; response_count:1; response_revision:17613; }","duration":"265.056981ms","start":"2026-04-02T17:27:59.736496Z","end":"2026-04-02T17:28:00.001553Z","steps":["trace[608020013] 'agreement among raft nodes before linearized reading' (duration: 264.836657ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:28:00.130275Z","caller":"traceutil/trace.go:171","msg":"trace[1819788615] linearizableReadLoop","detail":"{readStateIndex:18247; appliedIndex:18246; }","duration":"118.29446ms","start":"2026-04-02T17:28:00.011947Z","end":"2026-04-02T17:28:00.130241Z","steps":["trace[1819788615] 'read index received' (duration: 117.994875ms)","trace[1819788615] 'applied index is now lower than readState.Index' (duration: 294.425µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:28:00.13039Z","caller":"traceutil/trace.go:171","msg":"trace[1138847187] transaction","detail":"{read_only:false; response_revision:17614; number_of_response:1; }","duration":"119.227378ms","start":"2026-04-02T17:28:00.011122Z","end":"2026-04-02T17:28:00.130349Z","steps":["trace[1138847187] 'process raft request' (duration: 118.835101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:28:00.130518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.533344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:28:00.130635Z","caller":"traceutil/trace.go:171","msg":"trace[1262326367] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:17614; }","duration":"118.714428ms","start":"2026-04-02T17:28:00.011895Z","end":"2026-04-02T17:28:00.130609Z","steps":["trace[1262326367] 'agreement among raft nodes before linearized reading' (duration: 118.480913ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:29:05.678453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15865} {"level":"info","ts":"2026-04-02T17:29:05.76286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15865,"took":"81.250311ms","hash":1677405245,"current-db-size-bytes":42496000,"current-db-size":"42 MB","current-db-size-in-use-bytes":31293440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-02T17:29:05.762927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1677405245,"revision":15865,"compact-revision":13556} {"level":"warn","ts":"2026-04-02T17:29:25.920861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.417664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-h9dt4\" ","response":"range_response_count:1 size:22135"} {"level":"info","ts":"2026-04-02T17:29:25.920992Z","caller":"traceutil/trace.go:171","msg":"trace[1920401459] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-h9dt4; range_end:; response_count:1; response_revision:18427; }","duration":"138.625809ms","start":"2026-04-02T17:29:25.782339Z","end":"2026-04-02T17:29:25.920965Z","steps":["trace[1920401459] 'range keys from in-memory index tree' (duration: 138.126919ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:31:32.28008Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T17:31:32.286553Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-02T17:31:32.286654Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-02T17:34:05.685912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18325} {"level":"info","ts":"2026-04-02T17:34:05.777518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18325,"took":"87.982427ms","hash":389675526,"current-db-size-bytes":45092864,"current-db-size":"45 MB","current-db-size-in-use-bytes":32563200,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-02T17:34:05.777674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":389675526,"revision":18325,"compact-revision":15865} {"level":"info","ts":"2026-04-02T17:35:08.853672Z","caller":"traceutil/trace.go:171","msg":"trace[1257962774] transaction","detail":"{read_only:false; response_revision:20994; number_of_response:1; }","duration":"249.010043ms","start":"2026-04-02T17:35:08.604616Z","end":"2026-04-02T17:35:08.853626Z","steps":["trace[1257962774] 'process raft request' (duration: 248.757658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:35:09.217483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.932594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-04-02T17:35:09.217643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.876383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-02T17:35:09.217687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.771521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T17:35:09.217777Z","caller":"traceutil/trace.go:171","msg":"trace[1203475907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20995; }","duration":"283.105937ms","start":"2026-04-02T17:35:08.934646Z","end":"2026-04-02T17:35:09.217752Z","steps":["trace[1203475907] 'range keys from in-memory index tree' (duration: 282.508306ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.2178Z","caller":"traceutil/trace.go:171","msg":"trace[1111785572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20995; }","duration":"282.951654ms","start":"2026-04-02T17:35:08.934822Z","end":"2026-04-02T17:35:09.217774Z","steps":["trace[1111785572] 'range keys from in-memory index tree' (duration: 282.519386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:35:09.217778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.998295ms","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-02T17:35:09.217817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.04491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-02T17:35:09.217944Z","caller":"traceutil/trace.go:171","msg":"trace[74957400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20995; }","duration":"283.178958ms","start":"2026-04-02T17:35:08.934749Z","end":"2026-04-02T17:35:09.217928Z","steps":["trace[74957400] 'range keys from in-memory index tree' (duration: 282.694329ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.217988Z","caller":"traceutil/trace.go:171","msg":"trace[882749782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20995; }","duration":"283.447313ms","start":"2026-04-02T17:35:08.934506Z","end":"2026-04-02T17:35:09.217954Z","steps":["trace[882749782] 'range keys from in-memory index tree' (duration: 272.741124ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.21827Z","caller":"traceutil/trace.go:171","msg":"trace[1552363456] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20995; }","duration":"290.22256ms","start":"2026-04-02T17:35:08.927456Z","end":"2026-04-02T17:35:09.217679Z","steps":["trace[1552363456] 'range keys from in-memory index tree' (duration: 289.664969ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.343736Z","caller":"traceutil/trace.go:171","msg":"trace[1242317644] transaction","detail":"{read_only:false; response_revision:20996; number_of_response:1; }","duration":"244.602731ms","start":"2026-04-02T17:35:09.099101Z","end":"2026-04-02T17:35:09.343704Z","steps":["trace[1242317644] 'process raft request' (duration: 244.330346ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.38664Z","caller":"traceutil/trace.go:171","msg":"trace[1440484559] transaction","detail":"{read_only:false; response_revision:20998; number_of_response:1; }","duration":"163.567203ms","start":"2026-04-02T17:35:09.223053Z","end":"2026-04-02T17:35:09.38662Z","steps":["trace[1440484559] 'process raft request' (duration: 163.473671ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.386719Z","caller":"traceutil/trace.go:171","msg":"trace[1290957054] transaction","detail":"{read_only:false; response_revision:20997; number_of_response:1; }","duration":"163.738646ms","start":"2026-04-02T17:35:09.222931Z","end":"2026-04-02T17:35:09.38667Z","steps":["trace[1290957054] 'process raft request' (duration: 163.451821ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.386965Z","caller":"traceutil/trace.go:171","msg":"trace[333199488] transaction","detail":"{read_only:false; response_revision:20999; number_of_response:1; }","duration":"163.800628ms","start":"2026-04-02T17:35:09.223133Z","end":"2026-04-02T17:35:09.386933Z","steps":["trace[333199488] 'process raft request' (duration: 163.436141ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.387318Z","caller":"traceutil/trace.go:171","msg":"trace[1261284257] transaction","detail":"{read_only:false; response_revision:21000; number_of_response:1; }","duration":"164.008821ms","start":"2026-04-02T17:35:09.22329Z","end":"2026-04-02T17:35:09.387299Z","steps":["trace[1261284257] 'process raft request' (duration: 163.549383ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.387371Z","caller":"traceutil/trace.go:171","msg":"trace[1577573528] linearizableReadLoop","detail":"{readStateIndex:21741; appliedIndex:21735; }","duration":"117.693049ms","start":"2026-04-02T17:35:09.269654Z","end":"2026-04-02T17:35:09.387347Z","steps":["trace[1577573528] 'read index received' (duration: 73.741302ms)","trace[1577573528] 'applied index is now lower than readState.Index' (duration: 43.950507ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:35:09.387484Z","caller":"traceutil/trace.go:171","msg":"trace[1187713892] transaction","detail":"{read_only:false; response_revision:21002; number_of_response:1; }","duration":"100.371196ms","start":"2026-04-02T17:35:09.287099Z","end":"2026-04-02T17:35:09.38747Z","steps":["trace[1187713892] 'process raft request' (duration: 100.192563ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:09.387425Z","caller":"traceutil/trace.go:171","msg":"trace[1444531595] transaction","detail":"{read_only:false; response_revision:21001; number_of_response:1; }","duration":"163.676065ms","start":"2026-04-02T17:35:09.22372Z","end":"2026-04-02T17:35:09.387396Z","steps":["trace[1444531595] 'process raft request' (duration: 163.441141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:35:09.387534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.848603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:35:09.387583Z","caller":"traceutil/trace.go:171","msg":"trace[1168741144] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:21002; }","duration":"117.947225ms","start":"2026-04-02T17:35:09.269622Z","end":"2026-04-02T17:35:09.387569Z","steps":["trace[1168741144] 'agreement among raft nodes before linearized reading' (duration: 117.803841ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:35:18.799864Z","caller":"traceutil/trace.go:171","msg":"trace[536406416] linearizableReadLoop","detail":"{readStateIndex:21938; appliedIndex:21937; }","duration":"107.996099ms","start":"2026-04-02T17:35:18.691805Z","end":"2026-04-02T17:35:18.799801Z","steps":["trace[536406416] 'read index received' (duration: 107.731704ms)","trace[536406416] 'applied index is now lower than readState.Index' (duration: 263.555µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:35:18.800047Z","caller":"traceutil/trace.go:171","msg":"trace[1338982800] transaction","detail":"{read_only:false; response_revision:21191; number_of_response:1; }","duration":"109.661939ms","start":"2026-04-02T17:35:18.690293Z","end":"2026-04-02T17:35:18.799955Z","steps":["trace[1338982800] 'process raft request' (duration: 109.348404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:35:18.800127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.293645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-04-02T17:35:18.800222Z","caller":"traceutil/trace.go:171","msg":"trace[1354077654] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:21191; }","duration":"108.415157ms","start":"2026-04-02T17:35:18.691783Z","end":"2026-04-02T17:35:18.800198Z","steps":["trace[1354077654] 'agreement among raft nodes before linearized reading' (duration: 108.188243ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:39:05.695095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20550} {"level":"info","ts":"2026-04-02T17:39:05.790496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20550,"took":"91.517857ms","hash":1773883701,"current-db-size-bytes":45936640,"current-db-size":"46 MB","current-db-size-in-use-bytes":33894400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-02T17:39:05.790559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1773883701,"revision":20550,"compact-revision":18325} {"level":"info","ts":"2026-04-02T17:40:46.775759Z","caller":"traceutil/trace.go:171","msg":"trace[916815643] transaction","detail":"{read_only:false; response_revision:23627; number_of_response:1; }","duration":"109.543292ms","start":"2026-04-02T17:40:46.666188Z","end":"2026-04-02T17:40:46.775731Z","steps":["trace[916815643] 'process raft request' (duration: 109.363209ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:40:47.177562Z","caller":"traceutil/trace.go:171","msg":"trace[2041415079] linearizableReadLoop","detail":"{readStateIndex:24455; appliedIndex:24454; }","duration":"206.369237ms","start":"2026-04-02T17:40:46.971162Z","end":"2026-04-02T17:40:47.177531Z","steps":["trace[2041415079] 'read index received' (duration: 206.023351ms)","trace[2041415079] 'applied index is now lower than readState.Index' (duration: 344.716µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:40:47.17767Z","caller":"traceutil/trace.go:171","msg":"trace[65421041] transaction","detail":"{read_only:false; response_revision:23629; number_of_response:1; }","duration":"214.204514ms","start":"2026-04-02T17:40:46.963424Z","end":"2026-04-02T17:40:47.177629Z","steps":["trace[65421041] 'process raft request' (duration: 213.915359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:40:47.177745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.564211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:40:47.177888Z","caller":"traceutil/trace.go:171","msg":"trace[338104134] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:23629; }","duration":"206.696084ms","start":"2026-04-02T17:40:46.971093Z","end":"2026-04-02T17:40:47.177789Z","steps":["trace[338104134] 'agreement among raft nodes before linearized reading' (duration: 206.552061ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:05.70121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22899} {"level":"info","ts":"2026-04-02T17:44:05.811788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22899,"took":"106.279252ms","hash":261491476,"current-db-size-bytes":47624192,"current-db-size":"48 MB","current-db-size-in-use-bytes":30445568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-02T17:44:05.811881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":261491476,"revision":22899,"compact-revision":20550} {"level":"info","ts":"2026-04-02T17:44:34.781577Z","caller":"traceutil/trace.go:171","msg":"trace[1578369180] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"516.294727ms","start":"2026-04-02T17:44:34.265252Z","end":"2026-04-02T17:44:34.781547Z","steps":["trace[1578369180] 'process raft request' (duration: 516.113024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:34.781795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:44:34.265219Z","time spent":"516.46827ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:44:34.783613Z","caller":"traceutil/trace.go:171","msg":"trace[1560855268] linearizableReadLoop","detail":"{readStateIndex:26886; appliedIndex:26884; }","duration":"269.526455ms","start":"2026-04-02T17:44:34.514047Z","end":"2026-04-02T17:44:34.783574Z","steps":["trace[1560855268] 'read index received' (duration: 267.205802ms)","trace[1560855268] 'applied index is now lower than readState.Index' (duration: 2.319753ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:44:34.783662Z","caller":"traceutil/trace.go:171","msg":"trace[1944730570] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"516.306667ms","start":"2026-04-02T17:44:34.267314Z","end":"2026-04-02T17:44:34.783621Z","steps":["trace[1944730570] 'process raft request' (duration: 516.048412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:34.783822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.74876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-02T17:44:34.783857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:44:34.267286Z","time spent":"516.47078ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:44:34.783892Z","caller":"traceutil/trace.go:171","msg":"trace[112415274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25983; }","duration":"269.911703ms","start":"2026-04-02T17:44:34.513969Z","end":"2026-04-02T17:44:34.783881Z","steps":["trace[112415274] 'agreement among raft nodes before linearized reading' (duration: 269.72095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:34.784042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.585308ms","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-02T17:44:34.784148Z","caller":"traceutil/trace.go:171","msg":"trace[1234390351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25983; }","duration":"135.713261ms","start":"2026-04-02T17:44:34.648413Z","end":"2026-04-02T17:44:34.784126Z","steps":["trace[1234390351] 'agreement among raft nodes before linearized reading' (duration: 135.419425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:34.784185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.204186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-02T17:44:34.784224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.024519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-02T17:44:34.784308Z","caller":"traceutil/trace.go:171","msg":"trace[135704548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25983; }","duration":"109.355229ms","start":"2026-04-02T17:44:34.674921Z","end":"2026-04-02T17:44:34.784276Z","steps":["trace[135704548] 'agreement among raft nodes before linearized reading' (duration: 109.015293ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:34.784317Z","caller":"traceutil/trace.go:171","msg":"trace[621761564] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25983; }","duration":"105.12676ms","start":"2026-04-02T17:44:34.679162Z","end":"2026-04-02T17:44:34.784289Z","steps":["trace[621761564] 'agreement among raft nodes before linearized reading' (duration: 104.830485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:34.78435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.754635ms","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-02T17:44:34.784407Z","caller":"traceutil/trace.go:171","msg":"trace[833447316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25983; }","duration":"110.827066ms","start":"2026-04-02T17:44:34.673566Z","end":"2026-04-02T17:44:34.784393Z","steps":["trace[833447316] 'agreement among raft nodes before linearized reading' (duration: 110.655813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:34.784522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.610965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T17:44:34.784632Z","caller":"traceutil/trace.go:171","msg":"trace[170467424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25983; }","duration":"130.780818ms","start":"2026-04-02T17:44:34.653816Z","end":"2026-04-02T17:44:34.784597Z","steps":["trace[170467424] 'agreement among raft nodes before linearized reading' (duration: 130.508403ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:47:53.123974Z","caller":"traceutil/trace.go:171","msg":"trace[1808941868] transaction","detail":"{read_only:false; response_revision:27996; number_of_response:1; }","duration":"125.203075ms","start":"2026-04-02T17:47:52.998748Z","end":"2026-04-02T17:47:53.123951Z","steps":["trace[1808941868] 'process raft request' (duration: 125.068532ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:47:55.724819Z","caller":"traceutil/trace.go:171","msg":"trace[1065430157] transaction","detail":"{read_only:false; response_revision:28015; number_of_response:1; }","duration":"110.147614ms","start":"2026-04-02T17:47:55.614632Z","end":"2026-04-02T17:47:55.724779Z","steps":["trace[1065430157] 'process raft request' (duration: 109.93087ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:47:55.871543Z","caller":"traceutil/trace.go:171","msg":"trace[1791196116] linearizableReadLoop","detail":"{readStateIndex:28970; appliedIndex:28968; }","duration":"127.296204ms","start":"2026-04-02T17:47:55.744227Z","end":"2026-04-02T17:47:55.871523Z","steps":["trace[1791196116] 'read index received' (duration: 127.09726ms)","trace[1791196116] 'applied index is now lower than readState.Index' (duration: 197.834µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:47:55.871817Z","caller":"traceutil/trace.go:171","msg":"trace[2131808215] transaction","detail":"{read_only:false; response_revision:28016; number_of_response:1; }","duration":"198.648704ms","start":"2026-04-02T17:47:55.673158Z","end":"2026-04-02T17:47:55.871807Z","steps":["trace[2131808215] 'process raft request' (duration: 198.192146ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:47:55.871996Z","caller":"traceutil/trace.go:171","msg":"trace[1642508638] transaction","detail":"{read_only:false; response_revision:28017; number_of_response:1; }","duration":"168.197786ms","start":"2026-04-02T17:47:55.703788Z","end":"2026-04-02T17:47:55.871986Z","steps":["trace[1642508638] 'process raft request' (duration: 167.696457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:47:55.872144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.887615ms","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-02T17:47:55.872204Z","caller":"traceutil/trace.go:171","msg":"trace[1371741260] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28017; }","duration":"128.023767ms","start":"2026-04-02T17:47:55.744145Z","end":"2026-04-02T17:47:55.872169Z","steps":["trace[1371741260] 'agreement among raft nodes before linearized reading' (duration: 127.937806ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:47:57.313754Z","caller":"traceutil/trace.go:171","msg":"trace[527014523] linearizableReadLoop","detail":"{readStateIndex:28980; appliedIndex:28978; }","duration":"170.704463ms","start":"2026-04-02T17:47:57.143018Z","end":"2026-04-02T17:47:57.313723Z","steps":["trace[527014523] 'read index received' (duration: 54.427595ms)","trace[527014523] 'applied index is now lower than readState.Index' (duration: 116.275568ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:47:57.313859Z","caller":"traceutil/trace.go:171","msg":"trace[1428573316] transaction","detail":"{read_only:false; response_revision:28027; number_of_response:1; }","duration":"173.972634ms","start":"2026-04-02T17:47:57.13983Z","end":"2026-04-02T17:47:57.313802Z","steps":["trace[1428573316] 'process raft request' (duration: 111.712554ms)","trace[1428573316] 'compare' (duration: 61.819472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:47:57.314012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.980969ms","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-02T17:47:57.314099Z","caller":"traceutil/trace.go:171","msg":"trace[1105109517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28027; }","duration":"171.12501ms","start":"2026-04-02T17:47:57.142956Z","end":"2026-04-02T17:47:57.314081Z","steps":["trace[1105109517] 'agreement among raft nodes before linearized reading' (duration: 170.872996ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:47:58.834686Z","caller":"traceutil/trace.go:171","msg":"trace[1899906895] transaction","detail":"{read_only:false; response_revision:28039; number_of_response:1; }","duration":"115.981891ms","start":"2026-04-02T17:47:58.718672Z","end":"2026-04-02T17:47:58.834654Z","steps":["trace[1899906895] 'process raft request' (duration: 115.790428ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:47:59.062233Z","caller":"traceutil/trace.go:171","msg":"trace[1930626116] linearizableReadLoop","detail":"{readStateIndex:28994; appliedIndex:28993; }","duration":"135.780152ms","start":"2026-04-02T17:47:58.926426Z","end":"2026-04-02T17:47:59.062206Z","steps":["trace[1930626116] 'read index received' (duration: 46.237112ms)","trace[1930626116] 'applied index is now lower than readState.Index' (duration: 89.5419ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:47:59.062297Z","caller":"traceutil/trace.go:171","msg":"trace[1826555008] transaction","detail":"{read_only:false; response_revision:28040; number_of_response:1; }","duration":"150.932225ms","start":"2026-04-02T17:47:58.911312Z","end":"2026-04-02T17:47:59.062244Z","steps":["trace[1826555008] 'process raft request' (duration: 61.417475ms)","trace[1826555008] 'compare' (duration: 89.340426ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:47:59.062502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.064527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-04-02T17:47:59.06255Z","caller":"traceutil/trace.go:171","msg":"trace[1854221998] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:28040; }","duration":"136.151969ms","start":"2026-04-02T17:47:58.926385Z","end":"2026-04-02T17:47:59.062537Z","steps":["trace[1854221998] 'agreement among raft nodes before linearized reading' (duration: 135.956295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:47:59.0628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.311676ms","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-02T17:47:59.062843Z","caller":"traceutil/trace.go:171","msg":"trace[1725216718] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:28040; }","duration":"125.374687ms","start":"2026-04-02T17:47:58.937457Z","end":"2026-04-02T17:47:59.062832Z","steps":["trace[1725216718] 'agreement among raft nodes before linearized reading' (duration: 125.276185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:47:59.66713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.705906ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:47:59.66729Z","caller":"traceutil/trace.go:171","msg":"trace[490647938] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:28043; }","duration":"153.884219ms","start":"2026-04-02T17:47:59.513383Z","end":"2026-04-02T17:47:59.667268Z","steps":["trace[490647938] 'range keys from in-memory index tree' (duration: 153.683346ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:00.01175Z","caller":"traceutil/trace.go:171","msg":"trace[496569640] transaction","detail":"{read_only:false; response_revision:28046; number_of_response:1; }","duration":"126.828834ms","start":"2026-04-02T17:47:59.884893Z","end":"2026-04-02T17:48:00.011722Z","steps":["trace[496569640] 'process raft request' (duration: 126.63172ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:00.170843Z","caller":"traceutil/trace.go:171","msg":"trace[2126881048] transaction","detail":"{read_only:false; response_revision:28047; number_of_response:1; }","duration":"152.354081ms","start":"2026-04-02T17:48:00.018433Z","end":"2026-04-02T17:48:00.170787Z","steps":["trace[2126881048] 'process raft request' (duration: 63.307941ms)","trace[2126881048] 'compare' (duration: 88.894838ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:00.209379Z","caller":"traceutil/trace.go:171","msg":"trace[1778432393] transaction","detail":"{read_only:false; response_revision:28048; number_of_response:1; }","duration":"189.959752ms","start":"2026-04-02T17:48:00.019386Z","end":"2026-04-02T17:48:00.209345Z","steps":["trace[1778432393] 'process raft request' (duration: 189.703037ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:00.209695Z","caller":"traceutil/trace.go:171","msg":"trace[1764076043] transaction","detail":"{read_only:false; response_revision:28049; number_of_response:1; }","duration":"190.288398ms","start":"2026-04-02T17:48:00.019384Z","end":"2026-04-02T17:48:00.209673Z","steps":["trace[1764076043] 'process raft request' (duration: 189.906901ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:00.209916Z","caller":"traceutil/trace.go:171","msg":"trace[1110074851] transaction","detail":"{read_only:false; response_revision:28050; number_of_response:1; }","duration":"190.281318ms","start":"2026-04-02T17:48:00.019623Z","end":"2026-04-02T17:48:00.209905Z","steps":["trace[1110074851] 'process raft request' (duration: 189.963091ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:00.210251Z","caller":"traceutil/trace.go:171","msg":"trace[1731176297] linearizableReadLoop","detail":"{readStateIndex:29005; appliedIndex:29000; }","duration":"189.81835ms","start":"2026-04-02T17:48:00.020422Z","end":"2026-04-02T17:48:00.21024Z","steps":["trace[1731176297] 'read index received' (duration: 61.351084ms)","trace[1731176297] 'applied index is now lower than readState.Index' (duration: 128.466186ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:00.210406Z","caller":"traceutil/trace.go:171","msg":"trace[1298209226] transaction","detail":"{read_only:false; response_revision:28051; number_of_response:1; }","duration":"190.633454ms","start":"2026-04-02T17:48:00.019757Z","end":"2026-04-02T17:48:00.21039Z","steps":["trace[1298209226] 'process raft request' (duration: 190.108975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:00.211763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.317477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12331"} {"level":"info","ts":"2026-04-02T17:48:00.211843Z","caller":"traceutil/trace.go:171","msg":"trace[1850982436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28051; }","duration":"191.43487ms","start":"2026-04-02T17:48:00.02039Z","end":"2026-04-02T17:48:00.211825Z","steps":["trace[1850982436] 'agreement among raft nodes before linearized reading' (duration: 189.889871ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:00.247787Z","caller":"traceutil/trace.go:171","msg":"trace[131232917] transaction","detail":"{read_only:false; response_revision:28052; number_of_response:1; }","duration":"134.721592ms","start":"2026-04-02T17:48:00.113038Z","end":"2026-04-02T17:48:00.247759Z","steps":["trace[131232917] 'process raft request' (duration: 134.507369ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:00.34862Z","caller":"traceutil/trace.go:171","msg":"trace[1063101501] linearizableReadLoop","detail":"{readStateIndex:29007; appliedIndex:29005; }","duration":"135.375425ms","start":"2026-04-02T17:48:00.213215Z","end":"2026-04-02T17:48:00.348591Z","steps":["trace[1063101501] 'read index received' (duration: 34.28792ms)","trace[1063101501] 'applied index is now lower than readState.Index' (duration: 101.086455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:00.349301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.069577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18368"} {"level":"info","ts":"2026-04-02T17:48:00.349356Z","caller":"traceutil/trace.go:171","msg":"trace[1445467121] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:28053; }","duration":"136.198429ms","start":"2026-04-02T17:48:00.213145Z","end":"2026-04-02T17:48:00.349344Z","steps":["trace[1445467121] 'agreement among raft nodes before linearized reading' (duration: 136.001106ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:00.349457Z","caller":"traceutil/trace.go:171","msg":"trace[935678887] transaction","detail":"{read_only:false; response_revision:28053; number_of_response:1; }","duration":"137.457333ms","start":"2026-04-02T17:48:00.211973Z","end":"2026-04-02T17:48:00.34943Z","steps":["trace[935678887] 'process raft request' (duration: 78.033105ms)","trace[935678887] 'compare' (duration: 58.346548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:00.349335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.416568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-02T17:48:00.349906Z","caller":"traceutil/trace.go:171","msg":"trace[1871327607] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:28053; }","duration":"104.059691ms","start":"2026-04-02T17:48:00.245834Z","end":"2026-04-02T17:48:00.349893Z","steps":["trace[1871327607] 'agreement among raft nodes before linearized reading' (duration: 103.341017ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:01.581447Z","caller":"traceutil/trace.go:171","msg":"trace[1171031380] linearizableReadLoop","detail":"{readStateIndex:29010; appliedIndex:29009; }","duration":"123.502712ms","start":"2026-04-02T17:48:01.457917Z","end":"2026-04-02T17:48:01.581419Z","steps":["trace[1171031380] 'read index received' (duration: 123.263198ms)","trace[1171031380] 'applied index is now lower than readState.Index' (duration: 238.114µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:01.581921Z","caller":"traceutil/trace.go:171","msg":"trace[1446552443] transaction","detail":"{read_only:false; response_revision:28056; number_of_response:1; }","duration":"188.434234ms","start":"2026-04-02T17:48:01.393473Z","end":"2026-04-02T17:48:01.581908Z","steps":["trace[1446552443] 'process raft request' (duration: 187.69943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:01.582292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.361809ms","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-02T17:48:01.582387Z","caller":"traceutil/trace.go:171","msg":"trace[2087711437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28056; }","duration":"124.508491ms","start":"2026-04-02T17:48:01.457866Z","end":"2026-04-02T17:48:01.582374Z","steps":["trace[2087711437] 'agreement among raft nodes before linearized reading' (duration: 124.304318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:01.58249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.680128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-7f486dc58f-zmqn6\" ","response":"range_response_count:1 size:8649"} {"level":"info","ts":"2026-04-02T17:48:01.582559Z","caller":"traceutil/trace.go:171","msg":"trace[1776628951] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-7f486dc58f-zmqn6; range_end:; response_count:1; response_revision:28056; }","duration":"107.80428ms","start":"2026-04-02T17:48:01.474739Z","end":"2026-04-02T17:48:01.582543Z","steps":["trace[1776628951] 'agreement among raft nodes before linearized reading' (duration: 107.612327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:15.85306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.307019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-02T17:48:15.853217Z","caller":"traceutil/trace.go:171","msg":"trace[1693562460] linearizableReadLoop","detail":"{readStateIndex:29180; appliedIndex:29179; }","duration":"200.518809ms","start":"2026-04-02T17:48:15.652677Z","end":"2026-04-02T17:48:15.853195Z","steps":["trace[1693562460] 'read index received' (duration: 83.941415ms)","trace[1693562460] 'applied index is now lower than readState.Index' (duration: 116.575564ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:15.853562Z","caller":"traceutil/trace.go:171","msg":"trace[722125550] transaction","detail":"{read_only:false; response_revision:28222; number_of_response:1; }","duration":"223.311854ms","start":"2026-04-02T17:48:15.630181Z","end":"2026-04-02T17:48:15.853493Z","steps":["trace[722125550] 'process raft request' (duration: 106.426044ms)","trace[722125550] 'compare' (duration: 116.140406ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:15.853657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.964758ms","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-02T17:48:15.853729Z","caller":"traceutil/trace.go:171","msg":"trace[233423599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28222; }","duration":"201.139281ms","start":"2026-04-02T17:48:15.652575Z","end":"2026-04-02T17:48:15.853714Z","steps":["trace[233423599] 'agreement among raft nodes before linearized reading' (duration: 200.972438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:15.911763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.908527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d48c4e0-e06f-43c8-b254-47f2bb72ac54\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-02T17:48:15.911858Z","caller":"traceutil/trace.go:171","msg":"trace[1438023219] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2d48c4e0-e06f-43c8-b254-47f2bb72ac54; range_end:; response_count:1; response_revision:28223; }","duration":"259.04807ms","start":"2026-04-02T17:48:15.652788Z","end":"2026-04-02T17:48:15.911836Z","steps":["trace[1438023219] 'agreement among raft nodes before linearized reading' (duration: 258.829016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:15.912082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.123755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d48c4e0-e06f-43c8-b254-47f2bb72ac54\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-02T17:48:15.912115Z","caller":"traceutil/trace.go:171","msg":"trace[2009268878] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2d48c4e0-e06f-43c8-b254-47f2bb72ac54; range_end:; response_count:1; response_revision:28223; }","duration":"227.163125ms","start":"2026-04-02T17:48:15.68494Z","end":"2026-04-02T17:48:15.912103Z","steps":["trace[2009268878] 'agreement among raft nodes before linearized reading' (duration: 227.078564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:15.912271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.378109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:15.9123Z","caller":"traceutil/trace.go:171","msg":"trace[1154920744] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:28223; }","duration":"228.42371ms","start":"2026-04-02T17:48:15.683866Z","end":"2026-04-02T17:48:15.91229Z","steps":["trace[1154920744] 'agreement among raft nodes before linearized reading' (duration: 228.367399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:15.912461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.619727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:15.912489Z","caller":"traceutil/trace.go:171","msg":"trace[1822789648] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:28223; }","duration":"232.665258ms","start":"2026-04-02T17:48:15.679815Z","end":"2026-04-02T17:48:15.91248Z","steps":["trace[1822789648] 'agreement among raft nodes before linearized reading' (duration: 232.617177ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:16.152383Z","caller":"traceutil/trace.go:171","msg":"trace[1109851890] linearizableReadLoop","detail":"{readStateIndex:29182; appliedIndex:29181; }","duration":"234.809398ms","start":"2026-04-02T17:48:15.917543Z","end":"2026-04-02T17:48:16.152353Z","steps":["trace[1109851890] 'read index received' (duration: 234.604134ms)","trace[1109851890] 'applied index is now lower than readState.Index' (duration: 204.234µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:16.15259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.019223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:16.152573Z","caller":"traceutil/trace.go:171","msg":"trace[2088518152] transaction","detail":"{read_only:false; response_revision:28224; number_of_response:1; }","duration":"294.190285ms","start":"2026-04-02T17:48:15.858329Z","end":"2026-04-02T17:48:16.152519Z","steps":["trace[2088518152] 'process raft request' (duration: 293.826728ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:16.152675Z","caller":"traceutil/trace.go:171","msg":"trace[1477005360] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:28224; }","duration":"235.111834ms","start":"2026-04-02T17:48:15.917518Z","end":"2026-04-02T17:48:16.15263Z","steps":["trace[1477005360] 'agreement among raft nodes before linearized reading' (duration: 234.981262ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:16.372223Z","caller":"traceutil/trace.go:171","msg":"trace[1390915788] transaction","detail":"{read_only:false; response_revision:28225; number_of_response:1; }","duration":"452.177231ms","start":"2026-04-02T17:48:15.920005Z","end":"2026-04-02T17:48:16.372182Z","steps":["trace[1390915788] 'process raft request' (duration: 432.666407ms)","trace[1390915788] 'compare' (duration: 18.858972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:16.37244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:15.919993Z","time spent":"452.328674ms","remote":"127.0.0.1:38242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3740,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:48:16.372722Z","caller":"traceutil/trace.go:171","msg":"trace[728312252] linearizableReadLoop","detail":"{readStateIndex:29184; appliedIndex:29182; }","duration":"220.232926ms","start":"2026-04-02T17:48:16.152456Z","end":"2026-04-02T17:48:16.372689Z","steps":["trace[728312252] 'read index received' (duration: 200.341205ms)","trace[728312252] 'applied index is now lower than readState.Index' (duration: 19.889981ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:16.37299Z","caller":"traceutil/trace.go:171","msg":"trace[674965062] transaction","detail":"{read_only:false; response_revision:28226; number_of_response:1; }","duration":"323.776098ms","start":"2026-04-02T17:48:16.049198Z","end":"2026-04-02T17:48:16.372974Z","steps":["trace[674965062] 'process raft request' (duration: 322.894502ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:16.373275Z","caller":"traceutil/trace.go:171","msg":"trace[1424823682] transaction","detail":"{read_only:false; response_revision:28227; number_of_response:1; }","duration":"179.228192ms","start":"2026-04-02T17:48:16.194012Z","end":"2026-04-02T17:48:16.37324Z","steps":["trace[1424823682] 'process raft request' (duration: 178.503648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:16.373263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:16.049169Z","time spent":"323.971671ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:48:16.373509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.988768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-04-02T17:48:16.373587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.945204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:16.37367Z","caller":"traceutil/trace.go:171","msg":"trace[442051397] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:28227; }","duration":"380.070096ms","start":"2026-04-02T17:48:15.993577Z","end":"2026-04-02T17:48:16.373647Z","steps":["trace[442051397] 'agreement among raft nodes before linearized reading' (duration: 379.459606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:16.373739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:15.993569Z","time spent":"380.148888ms","remote":"127.0.0.1:45530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-04-02T17:48:16.373605Z","caller":"traceutil/trace.go:171","msg":"trace[1682345466] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:28227; }","duration":"363.147951ms","start":"2026-04-02T17:48:16.010433Z","end":"2026-04-02T17:48:16.373581Z","steps":["trace[1682345466] 'agreement among raft nodes before linearized reading' (duration: 362.599101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:16.373953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:16.010403Z","time spent":"363.478717ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":159,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-02T17:48:16.374926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.72675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12331"} {"level":"info","ts":"2026-04-02T17:48:16.374996Z","caller":"traceutil/trace.go:171","msg":"trace[1084162800] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28228; }","duration":"217.796871ms","start":"2026-04-02T17:48:16.157181Z","end":"2026-04-02T17:48:16.374977Z","steps":["trace[1084162800] 'agreement among raft nodes before linearized reading' (duration: 217.575927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:16.375595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.877949ms","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-02T17:48:16.37564Z","caller":"traceutil/trace.go:171","msg":"trace[24677331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28228; }","duration":"117.153125ms","start":"2026-04-02T17:48:16.258477Z","end":"2026-04-02T17:48:16.37563Z","steps":["trace[24677331] 'agreement among raft nodes before linearized reading' (duration: 116.805778ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:16.589867Z","caller":"traceutil/trace.go:171","msg":"trace[173690418] transaction","detail":"{read_only:false; response_revision:28231; number_of_response:1; }","duration":"209.313744ms","start":"2026-04-02T17:48:16.38052Z","end":"2026-04-02T17:48:16.589834Z","steps":["trace[173690418] 'process raft request' (duration: 208.832904ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:16.589833Z","caller":"traceutil/trace.go:171","msg":"trace[526456870] linearizableReadLoop","detail":"{readStateIndex:29189; appliedIndex:29186; }","duration":"208.888134ms","start":"2026-04-02T17:48:16.38092Z","end":"2026-04-02T17:48:16.589808Z","steps":["trace[526456870] 'read index received' (duration: 87.252317ms)","trace[526456870] 'applied index is now lower than readState.Index' (duration: 121.634577ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:16.590057Z","caller":"traceutil/trace.go:171","msg":"trace[812400212] transaction","detail":"{read_only:false; response_revision:28232; number_of_response:1; }","duration":"208.61661ms","start":"2026-04-02T17:48:16.381429Z","end":"2026-04-02T17:48:16.590046Z","steps":["trace[812400212] 'process raft request' (duration: 208.12996ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:16.590266Z","caller":"traceutil/trace.go:171","msg":"trace[29869910] transaction","detail":"{read_only:false; response_revision:28230; number_of_response:1; }","duration":"209.987105ms","start":"2026-04-02T17:48:16.380268Z","end":"2026-04-02T17:48:16.590255Z","steps":["trace[29869910] 'process raft request' (duration: 208.917505ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:16.590352Z","caller":"traceutil/trace.go:171","msg":"trace[1514212213] transaction","detail":"{read_only:false; response_revision:28233; number_of_response:1; }","duration":"208.60434ms","start":"2026-04-02T17:48:16.381739Z","end":"2026-04-02T17:48:16.590344Z","steps":["trace[1514212213] 'process raft request' (duration: 207.852115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:16.590698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.755972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-02T17:48:16.59074Z","caller":"traceutil/trace.go:171","msg":"trace[58991072] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:28233; }","duration":"209.841343ms","start":"2026-04-02T17:48:16.380889Z","end":"2026-04-02T17:48:16.59073Z","steps":["trace[58991072] 'agreement among raft nodes before linearized reading' (duration: 209.576958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:16.59097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.688837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-04-02T17:48:16.591Z","caller":"traceutil/trace.go:171","msg":"trace[1970679218] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:28233; }","duration":"203.737139ms","start":"2026-04-02T17:48:16.387253Z","end":"2026-04-02T17:48:16.59099Z","steps":["trace[1970679218] 'agreement among raft nodes before linearized reading' (duration: 203.617366ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:17.746314Z","caller":"traceutil/trace.go:171","msg":"trace[855771012] transaction","detail":"{read_only:false; response_revision:28237; number_of_response:1; }","duration":"131.207996ms","start":"2026-04-02T17:48:17.615063Z","end":"2026-04-02T17:48:17.746271Z","steps":["trace[855771012] 'process raft request' (duration: 62.805961ms)","trace[855771012] 'compare' (duration: 68.201361ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:18.299481Z","caller":"traceutil/trace.go:171","msg":"trace[233935682] transaction","detail":"{read_only:false; response_revision:28241; number_of_response:1; }","duration":"137.188598ms","start":"2026-04-02T17:48:18.16226Z","end":"2026-04-02T17:48:18.299449Z","steps":["trace[233935682] 'process raft request' (duration: 137.012804ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:18.503106Z","caller":"traceutil/trace.go:171","msg":"trace[105340773] linearizableReadLoop","detail":"{readStateIndex:29202; appliedIndex:29199; }","duration":"116.706015ms","start":"2026-04-02T17:48:18.386373Z","end":"2026-04-02T17:48:18.503079Z","steps":["trace[105340773] 'read index received' (duration: 63.33107ms)","trace[105340773] 'applied index is now lower than readState.Index' (duration: 53.373545ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:18.503455Z","caller":"traceutil/trace.go:171","msg":"trace[218259751] transaction","detail":"{read_only:false; response_revision:28243; number_of_response:1; }","duration":"118.54713ms","start":"2026-04-02T17:48:18.384891Z","end":"2026-04-02T17:48:18.503439Z","steps":["trace[218259751] 'process raft request' (duration: 117.9873ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:18.503699Z","caller":"traceutil/trace.go:171","msg":"trace[139755763] transaction","detail":"{read_only:false; response_revision:28244; number_of_response:1; }","duration":"118.844585ms","start":"2026-04-02T17:48:18.384842Z","end":"2026-04-02T17:48:18.503686Z","steps":["trace[139755763] 'process raft request' (duration: 118.185173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:18.50391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.50933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:18.503962Z","caller":"traceutil/trace.go:171","msg":"trace[538124091] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:28244; }","duration":"117.604023ms","start":"2026-04-02T17:48:18.38634Z","end":"2026-04-02T17:48:18.503944Z","steps":["trace[538124091] 'agreement among raft nodes before linearized reading' (duration: 117.502401ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:18.651073Z","caller":"traceutil/trace.go:171","msg":"trace[770389882] linearizableReadLoop","detail":"{readStateIndex:29203; appliedIndex:29202; }","duration":"133.059161ms","start":"2026-04-02T17:48:18.517981Z","end":"2026-04-02T17:48:18.65104Z","steps":["trace[770389882] 'read index received' (duration: 61.030248ms)","trace[770389882] 'applied index is now lower than readState.Index' (duration: 72.027543ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:18.651366Z","caller":"traceutil/trace.go:171","msg":"trace[987227310] transaction","detail":"{read_only:false; response_revision:28245; number_of_response:1; }","duration":"142.624349ms","start":"2026-04-02T17:48:18.508728Z","end":"2026-04-02T17:48:18.651353Z","steps":["trace[987227310] 'process raft request' (duration: 70.368761ms)","trace[987227310] 'compare' (duration: 71.81ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:18.651668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.652552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:18.651756Z","caller":"traceutil/trace.go:171","msg":"trace[1258299728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28245; }","duration":"133.782114ms","start":"2026-04-02T17:48:18.517955Z","end":"2026-04-02T17:48:18.651737Z","steps":["trace[1258299728] 'agreement among raft nodes before linearized reading' (duration: 133.410827ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:22.228301Z","caller":"traceutil/trace.go:171","msg":"trace[1063281043] transaction","detail":"{read_only:false; response_revision:28269; number_of_response:1; }","duration":"261.007237ms","start":"2026-04-02T17:48:21.967259Z","end":"2026-04-02T17:48:22.228267Z","steps":["trace[1063281043] 'process raft request' (duration: 260.747232ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:22.228882Z","caller":"traceutil/trace.go:171","msg":"trace[910985984] linearizableReadLoop","detail":"{readStateIndex:29229; appliedIndex:29227; }","duration":"161.544282ms","start":"2026-04-02T17:48:22.067311Z","end":"2026-04-02T17:48:22.228855Z","steps":["trace[910985984] 'read index received' (duration: 160.716916ms)","trace[910985984] 'applied index is now lower than readState.Index' (duration: 826.276µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:22.229108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.783876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d48c4e0-e06f-43c8-b254-47f2bb72ac54\" ","response":"range_response_count:1 size:3944"} {"level":"info","ts":"2026-04-02T17:48:22.229135Z","caller":"traceutil/trace.go:171","msg":"trace[1957914945] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2d48c4e0-e06f-43c8-b254-47f2bb72ac54; range_end:; response_count:1; response_revision:28270; }","duration":"161.879108ms","start":"2026-04-02T17:48:22.067248Z","end":"2026-04-02T17:48:22.229127Z","steps":["trace[1957914945] 'agreement among raft nodes before linearized reading' (duration: 161.705775ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:22.229226Z","caller":"traceutil/trace.go:171","msg":"trace[239613284] transaction","detail":"{read_only:false; response_revision:28270; number_of_response:1; }","duration":"244.512988ms","start":"2026-04-02T17:48:21.984688Z","end":"2026-04-02T17:48:22.229201Z","steps":["trace[239613284] 'process raft request' (duration: 244.03633ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:25.434367Z","caller":"traceutil/trace.go:171","msg":"trace[195632667] linearizableReadLoop","detail":"{readStateIndex:29273; appliedIndex:29272; }","duration":"103.439438ms","start":"2026-04-02T17:48:25.330891Z","end":"2026-04-02T17:48:25.43433Z","steps":["trace[195632667] 'read index received' (duration: 103.146283ms)","trace[195632667] 'applied index is now lower than readState.Index' (duration: 291.505µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:25.434487Z","caller":"traceutil/trace.go:171","msg":"trace[1621890450] transaction","detail":"{read_only:false; response_revision:28312; number_of_response:1; }","duration":"112.966075ms","start":"2026-04-02T17:48:25.321474Z","end":"2026-04-02T17:48:25.43444Z","steps":["trace[1621890450] 'process raft request' (duration: 112.607469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:25.43466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.744594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-04-02T17:48:25.434731Z","caller":"traceutil/trace.go:171","msg":"trace[1800519771] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:28312; }","duration":"103.881268ms","start":"2026-04-02T17:48:25.330828Z","end":"2026-04-02T17:48:25.43471Z","steps":["trace[1800519771] 'agreement among raft nodes before linearized reading' (duration: 103.650304ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:26.18235Z","caller":"traceutil/trace.go:171","msg":"trace[808596774] transaction","detail":"{read_only:false; response_revision:28317; number_of_response:1; }","duration":"176.222066ms","start":"2026-04-02T17:48:26.006079Z","end":"2026-04-02T17:48:26.182301Z","steps":["trace[808596774] 'process raft request' (duration: 171.275913ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:26.182823Z","caller":"traceutil/trace.go:171","msg":"trace[150124455] transaction","detail":"{read_only:false; response_revision:28319; number_of_response:1; }","duration":"176.185395ms","start":"2026-04-02T17:48:26.006605Z","end":"2026-04-02T17:48:26.18279Z","steps":["trace[150124455] 'process raft request' (duration: 175.919881ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:26.182839Z","caller":"traceutil/trace.go:171","msg":"trace[1739213598] transaction","detail":"{read_only:false; response_revision:28318; number_of_response:1; }","duration":"176.310907ms","start":"2026-04-02T17:48:26.006478Z","end":"2026-04-02T17:48:26.182788Z","steps":["trace[1739213598] 'process raft request' (duration: 175.666296ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:26.793363Z","caller":"traceutil/trace.go:171","msg":"trace[1316614833] transaction","detail":"{read_only:false; response_revision:28324; number_of_response:1; }","duration":"262.633637ms","start":"2026-04-02T17:48:26.530695Z","end":"2026-04-02T17:48:26.793329Z","steps":["trace[1316614833] 'process raft request' (duration: 262.314141ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:26.793564Z","caller":"traceutil/trace.go:171","msg":"trace[199798305] linearizableReadLoop","detail":"{readStateIndex:29286; appliedIndex:29284; }","duration":"109.174786ms","start":"2026-04-02T17:48:26.684356Z","end":"2026-04-02T17:48:26.79353Z","steps":["trace[199798305] 'read index received' (duration: 108.583245ms)","trace[199798305] 'applied index is now lower than readState.Index' (duration: 589.221µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:26.793659Z","caller":"traceutil/trace.go:171","msg":"trace[43758692] transaction","detail":"{read_only:false; response_revision:28325; number_of_response:1; }","duration":"261.412863ms","start":"2026-04-02T17:48:26.5322Z","end":"2026-04-02T17:48:26.793613Z","steps":["trace[43758692] 'process raft request' (duration: 261.057587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:26.794242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.826918ms","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-02T17:48:26.794443Z","caller":"traceutil/trace.go:171","msg":"trace[1848464755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28325; }","duration":"110.120844ms","start":"2026-04-02T17:48:26.684296Z","end":"2026-04-02T17:48:26.794417Z","steps":["trace[1848464755] 'agreement among raft nodes before linearized reading' (duration: 109.351299ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:38.942793Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000072cf.wal"} {"level":"warn","ts":"2026-04-02T17:48:47.263483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.772197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-02T17:48:47.263637Z","caller":"traceutil/trace.go:171","msg":"trace[417292174] transaction","detail":"{read_only:false; response_revision:28499; number_of_response:1; }","duration":"262.462824ms","start":"2026-04-02T17:48:47.001141Z","end":"2026-04-02T17:48:47.263604Z","steps":["trace[417292174] 'process raft request' (duration: 137.420302ms)","trace[417292174] 'compare' (duration: 124.143415ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:47.748536Z","caller":"traceutil/trace.go:171","msg":"trace[1999385248] transaction","detail":"{read_only:false; response_revision:28500; number_of_response:1; }","duration":"124.338409ms","start":"2026-04-02T17:48:47.624167Z","end":"2026-04-02T17:48:47.748505Z","steps":["trace[1999385248] 'process raft request' (duration: 124.087573ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:47.853812Z","caller":"traceutil/trace.go:171","msg":"trace[1309738164] transaction","detail":"{read_only:false; response_revision:28501; number_of_response:1; }","duration":"180.361693ms","start":"2026-04-02T17:48:47.673396Z","end":"2026-04-02T17:48:47.853758Z","steps":["trace[1309738164] 'process raft request' (duration: 97.409126ms)","trace[1309738164] 'compare' (duration: 82.768604ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:48.546585Z","caller":"traceutil/trace.go:171","msg":"trace[1141343832] transaction","detail":"{read_only:false; response_revision:28505; number_of_response:1; }","duration":"116.84963ms","start":"2026-04-02T17:48:48.429703Z","end":"2026-04-02T17:48:48.546552Z","steps":["trace[1141343832] 'process raft request' (duration: 116.598054ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:49.005113Z","caller":"traceutil/trace.go:171","msg":"trace[1859312928] transaction","detail":"{read_only:false; response_revision:28509; number_of_response:1; }","duration":"122.012774ms","start":"2026-04-02T17:48:48.883042Z","end":"2026-04-02T17:48:49.005055Z","steps":["trace[1859312928] 'process raft request' (duration: 121.826061ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:49.157992Z","caller":"traceutil/trace.go:171","msg":"trace[1243013137] linearizableReadLoop","detail":"{readStateIndex:29477; appliedIndex:29476; }","duration":"152.595185ms","start":"2026-04-02T17:48:49.00536Z","end":"2026-04-02T17:48:49.157955Z","steps":["trace[1243013137] 'read index received' (duration: 76.588228ms)","trace[1243013137] 'applied index is now lower than readState.Index' (duration: 76.005067ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:49.158057Z","caller":"traceutil/trace.go:171","msg":"trace[1736815485] transaction","detail":"{read_only:false; response_revision:28510; number_of_response:1; }","duration":"167.574724ms","start":"2026-04-02T17:48:48.990445Z","end":"2026-04-02T17:48:49.15802Z","steps":["trace[1736815485] 'process raft request' (duration: 91.482016ms)","trace[1736815485] 'compare' (duration: 75.673811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:49.158391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.125001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T17:48:49.158444Z","caller":"traceutil/trace.go:171","msg":"trace[1073717277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28510; }","duration":"156.236614ms","start":"2026-04-02T17:48:49.002194Z","end":"2026-04-02T17:48:49.158431Z","steps":["trace[1073717277] 'agreement among raft nodes before linearized reading' (duration: 155.926487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:49.158695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.912477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-02T17:48:49.158807Z","caller":"traceutil/trace.go:171","msg":"trace[1006325433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28510; }","duration":"179.069549ms","start":"2026-04-02T17:48:48.979708Z","end":"2026-04-02T17:48:49.158778Z","steps":["trace[1006325433] 'agreement among raft nodes before linearized reading' (duration: 178.705702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:49.158995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.303971ms","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-02T17:48:49.159062Z","caller":"traceutil/trace.go:171","msg":"trace[727949551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28512; }","duration":"143.413904ms","start":"2026-04-02T17:48:49.015636Z","end":"2026-04-02T17:48:49.15905Z","steps":["trace[727949551] 'agreement among raft nodes before linearized reading' (duration: 143.262171ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:49.159291Z","caller":"traceutil/trace.go:171","msg":"trace[1153103915] transaction","detail":"{read_only:false; response_revision:28512; number_of_response:1; }","duration":"148.670672ms","start":"2026-04-02T17:48:49.010609Z","end":"2026-04-02T17:48:49.15928Z","steps":["trace[1153103915] 'process raft request' (duration: 148.240194ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:49.159368Z","caller":"traceutil/trace.go:171","msg":"trace[972782424] transaction","detail":"{read_only:false; response_revision:28511; number_of_response:1; }","duration":"152.933451ms","start":"2026-04-02T17:48:49.006419Z","end":"2026-04-02T17:48:49.159353Z","steps":["trace[972782424] 'process raft request' (duration: 152.30713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:49.517015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.62768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-02T17:48:49.51736Z","caller":"traceutil/trace.go:171","msg":"trace[607538297] transaction","detail":"{read_only:false; response_revision:28515; number_of_response:1; }","duration":"352.825358ms","start":"2026-04-02T17:48:49.164503Z","end":"2026-04-02T17:48:49.517328Z","steps":["trace[607538297] 'process raft request' (duration: 352.598924ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:49.517482Z","caller":"traceutil/trace.go:171","msg":"trace[801501474] linearizableReadLoop","detail":"{readStateIndex:29482; appliedIndex:29480; }","duration":"328.277001ms","start":"2026-04-02T17:48:49.189184Z","end":"2026-04-02T17:48:49.517461Z","steps":["trace[801501474] 'read index received' (duration: 89.05397ms)","trace[801501474] 'applied index is now lower than readState.Index' (duration: 239.220721ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:49.517511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:49.164496Z","time spent":"352.92866ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:48:49.517617Z","caller":"traceutil/trace.go:171","msg":"trace[1804020725] transaction","detail":"{read_only:false; response_revision:28514; number_of_response:1; }","duration":"354.054621ms","start":"2026-04-02T17:48:49.163554Z","end":"2026-04-02T17:48:49.517609Z","steps":["trace[1804020725] 'process raft request' (duration: 114.744759ms)","trace[1804020725] 'compare' (duration: 238.220302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:49.517643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:49.163524Z","time spent":"354.104263ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:48:49.517825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.650707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-04-02T17:48:49.518021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.386475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-02T17:48:49.518124Z","caller":"traceutil/trace.go:171","msg":"trace[397366922] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28516; }","duration":"245.557368ms","start":"2026-04-02T17:48:49.272541Z","end":"2026-04-02T17:48:49.518098Z","steps":["trace[397366922] 'agreement among raft nodes before linearized reading' (duration: 245.258113ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:49.518985Z","caller":"traceutil/trace.go:171","msg":"trace[552249234] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:28516; }","duration":"328.720319ms","start":"2026-04-02T17:48:49.189138Z","end":"2026-04-02T17:48:49.517858Z","steps":["trace[552249234] 'agreement among raft nodes before linearized reading' (duration: 328.539695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:49.519126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:49.189115Z","time spent":"329.968783ms","remote":"127.0.0.1:38342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-04-02T17:48:49.822135Z","caller":"traceutil/trace.go:171","msg":"trace[2048956151] transaction","detail":"{read_only:false; response_revision:28517; number_of_response:1; }","duration":"298.240241ms","start":"2026-04-02T17:48:49.523854Z","end":"2026-04-02T17:48:49.822094Z","steps":["trace[2048956151] 'process raft request' (duration: 298.013916ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:49.822435Z","caller":"traceutil/trace.go:171","msg":"trace[1273074362] linearizableReadLoop","detail":"{readStateIndex:29484; appliedIndex:29483; }","duration":"217.23101ms","start":"2026-04-02T17:48:49.605185Z","end":"2026-04-02T17:48:49.822416Z","steps":["trace[1273074362] 'read index received' (duration: 216.412394ms)","trace[1273074362] 'applied index is now lower than readState.Index' (duration: 816.916µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:49.822619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.403354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-02T17:48:49.822899Z","caller":"traceutil/trace.go:171","msg":"trace[216865586] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:28517; }","duration":"217.712779ms","start":"2026-04-02T17:48:49.605168Z","end":"2026-04-02T17:48:49.822881Z","steps":["trace[216865586] 'agreement among raft nodes before linearized reading' (duration: 217.349053ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:49.997347Z","caller":"traceutil/trace.go:171","msg":"trace[424196273] transaction","detail":"{read_only:false; response_revision:28518; number_of_response:1; }","duration":"136.75308ms","start":"2026-04-02T17:48:49.860546Z","end":"2026-04-02T17:48:49.997299Z","steps":["trace[424196273] 'process raft request' (duration: 116.575323ms)","trace[424196273] 'compare' (duration: 19.890482ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:50.548854Z","caller":"traceutil/trace.go:171","msg":"trace[1402604622] transaction","detail":"{read_only:false; response_revision:28519; number_of_response:1; }","duration":"106.592368ms","start":"2026-04-02T17:48:50.44224Z","end":"2026-04-02T17:48:50.548832Z","steps":["trace[1402604622] 'process raft request' (duration: 106.371043ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:50.61538Z","caller":"traceutil/trace.go:171","msg":"trace[1017685526] linearizableReadLoop","detail":"{readStateIndex:29487; appliedIndex:29485; }","duration":"102.594912ms","start":"2026-04-02T17:48:50.512753Z","end":"2026-04-02T17:48:50.615348Z","steps":["trace[1017685526] 'read index received' (duration: 35.885618ms)","trace[1017685526] 'applied index is now lower than readState.Index' (duration: 66.707944ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:50.615595Z","caller":"traceutil/trace.go:171","msg":"trace[152550865] transaction","detail":"{read_only:false; response_revision:28520; number_of_response:1; }","duration":"125.02028ms","start":"2026-04-02T17:48:50.490552Z","end":"2026-04-02T17:48:50.615572Z","steps":["trace[152550865] 'process raft request' (duration: 124.638944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:50.615878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.081672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:50.615994Z","caller":"traceutil/trace.go:171","msg":"trace[1441964487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28520; }","duration":"103.323586ms","start":"2026-04-02T17:48:50.512641Z","end":"2026-04-02T17:48:50.615965Z","steps":["trace[1441964487] 'agreement among raft nodes before linearized reading' (duration: 102.976609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:50.816023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.972492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-02T17:48:50.816162Z","caller":"traceutil/trace.go:171","msg":"trace[1641929488] linearizableReadLoop","detail":"{readStateIndex:29488; appliedIndex:29487; }","duration":"200.675863ms","start":"2026-04-02T17:48:50.615465Z","end":"2026-04-02T17:48:50.816141Z","steps":["trace[1641929488] 'read index received' (duration: 69.385995ms)","trace[1641929488] 'applied index is now lower than readState.Index' (duration: 131.288308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:50.816602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.907982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:50.816585Z","caller":"traceutil/trace.go:171","msg":"trace[1886677135] transaction","detail":"{read_only:false; response_revision:28521; number_of_response:1; }","duration":"265.329328ms","start":"2026-04-02T17:48:50.551204Z","end":"2026-04-02T17:48:50.816533Z","steps":["trace[1886677135] 'process raft request' (duration: 133.719754ms)","trace[1886677135] 'compare' (duration: 130.809629ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:50.816723Z","caller":"traceutil/trace.go:171","msg":"trace[1792098913] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:28521; }","duration":"271.048664ms","start":"2026-04-02T17:48:50.545649Z","end":"2026-04-02T17:48:50.816698Z","steps":["trace[1792098913] 'agreement among raft nodes before linearized reading' (duration: 270.856991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:50.816908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.951292ms","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-02T17:48:50.817022Z","caller":"traceutil/trace.go:171","msg":"trace[1825396625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28521; }","duration":"234.097605ms","start":"2026-04-02T17:48:50.5829Z","end":"2026-04-02T17:48:50.816997Z","steps":["trace[1825396625] 'agreement among raft nodes before linearized reading' (duration: 233.596856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:50.849255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.112158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:50.849329Z","caller":"traceutil/trace.go:171","msg":"trace[923911938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28522; }","duration":"232.249621ms","start":"2026-04-02T17:48:50.617063Z","end":"2026-04-02T17:48:50.849312Z","steps":["trace[923911938] 'agreement among raft nodes before linearized reading' (duration: 232.050167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:50.849671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.759949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-02T17:48:50.849702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.938168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-02T17:48:50.849743Z","caller":"traceutil/trace.go:171","msg":"trace[1141541709] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:28522; }","duration":"104.03231ms","start":"2026-04-02T17:48:50.745696Z","end":"2026-04-02T17:48:50.849728Z","steps":["trace[1141541709] 'agreement among raft nodes before linearized reading' (duration: 103.889867ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:50.849737Z","caller":"traceutil/trace.go:171","msg":"trace[1054544347] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:28522; }","duration":"218.864521ms","start":"2026-04-02T17:48:50.630856Z","end":"2026-04-02T17:48:50.849721Z","steps":["trace[1054544347] 'agreement among raft nodes before linearized reading' (duration: 218.753868ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:51.10848Z","caller":"traceutil/trace.go:171","msg":"trace[754140164] transaction","detail":"{read_only:false; response_revision:28523; number_of_response:1; }","duration":"285.112086ms","start":"2026-04-02T17:48:50.823338Z","end":"2026-04-02T17:48:51.10845Z","steps":["trace[754140164] 'process raft request' (duration: 255.523684ms)","trace[754140164] 'compare' (duration: 28.907009ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:51.108775Z","caller":"traceutil/trace.go:171","msg":"trace[918351275] transaction","detail":"{read_only:false; response_revision:28524; number_of_response:1; }","duration":"176.238596ms","start":"2026-04-02T17:48:50.932498Z","end":"2026-04-02T17:48:51.108737Z","steps":["trace[918351275] 'process raft request' (duration: 175.516642ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:51.299748Z","caller":"traceutil/trace.go:171","msg":"trace[125850324] transaction","detail":"{read_only:false; response_revision:28526; number_of_response:1; }","duration":"134.357615ms","start":"2026-04-02T17:48:51.165355Z","end":"2026-04-02T17:48:51.299713Z","steps":["trace[125850324] 'process raft request' (duration: 91.643648ms)","trace[125850324] 'compare' (duration: 42.568005ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:51.32862Z","caller":"traceutil/trace.go:171","msg":"trace[578955096] linearizableReadLoop","detail":"{readStateIndex:29494; appliedIndex:29492; }","duration":"158.70197ms","start":"2026-04-02T17:48:51.169902Z","end":"2026-04-02T17:48:51.328604Z","steps":["trace[578955096] 'read index received' (duration: 87.116135ms)","trace[578955096] 'applied index is now lower than readState.Index' (duration: 71.584625ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:51.328834Z","caller":"traceutil/trace.go:171","msg":"trace[1511014647] transaction","detail":"{read_only:false; response_revision:28527; number_of_response:1; }","duration":"160.436742ms","start":"2026-04-02T17:48:51.168354Z","end":"2026-04-02T17:48:51.32879Z","steps":["trace[1511014647] 'process raft request' (duration: 160.160866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:51.328984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.028786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-02T17:48:51.32908Z","caller":"traceutil/trace.go:171","msg":"trace[195390255] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:28527; }","duration":"159.160338ms","start":"2026-04-02T17:48:51.169891Z","end":"2026-04-02T17:48:51.329052Z","steps":["trace[195390255] 'agreement among raft nodes before linearized reading' (duration: 158.859523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:51.329085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.839449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-02T17:48:51.329189Z","caller":"traceutil/trace.go:171","msg":"trace[1946417389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28527; }","duration":"139.011502ms","start":"2026-04-02T17:48:51.190147Z","end":"2026-04-02T17:48:51.329158Z","steps":["trace[1946417389] 'agreement among raft nodes before linearized reading' (duration: 138.698717ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:52.774839Z","caller":"traceutil/trace.go:171","msg":"trace[1813532950] linearizableReadLoop","detail":"{readStateIndex:29500; appliedIndex:29499; }","duration":"213.809956ms","start":"2026-04-02T17:48:52.561003Z","end":"2026-04-02T17:48:52.774813Z","steps":["trace[1813532950] 'read index received' (duration: 213.563322ms)","trace[1813532950] 'applied index is now lower than readState.Index' (duration: 245.484µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:52.775014Z","caller":"traceutil/trace.go:171","msg":"trace[994161159] transaction","detail":"{read_only:false; response_revision:28533; number_of_response:1; }","duration":"218.857511ms","start":"2026-04-02T17:48:52.556143Z","end":"2026-04-02T17:48:52.775Z","steps":["trace[994161159] 'process raft request' (duration: 218.484834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:52.77512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.688401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:48:52.775178Z","caller":"traceutil/trace.go:171","msg":"trace[1874442085] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:28533; }","duration":"113.798712ms","start":"2026-04-02T17:48:52.661366Z","end":"2026-04-02T17:48:52.775164Z","steps":["trace[1874442085] 'agreement among raft nodes before linearized reading' (duration: 113.68943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:52.775525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.487039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-02T17:48:52.775658Z","caller":"traceutil/trace.go:171","msg":"trace[2112664822] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:28533; }","duration":"214.700263ms","start":"2026-04-02T17:48:52.560928Z","end":"2026-04-02T17:48:52.775629Z","steps":["trace[2112664822] 'agreement among raft nodes before linearized reading' (duration: 214.435448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:53.017299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.557264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-02T17:48:53.017682Z","caller":"traceutil/trace.go:171","msg":"trace[1948741394] transaction","detail":"{read_only:false; response_revision:28534; number_of_response:1; }","duration":"194.850833ms","start":"2026-04-02T17:48:52.822778Z","end":"2026-04-02T17:48:53.017629Z","steps":["trace[1948741394] 'process raft request' (duration: 33.854141ms)","trace[1948741394] 'compare' (duration: 160.41532ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:53.017804Z","caller":"traceutil/trace.go:171","msg":"trace[648716343] transaction","detail":"{read_only:false; response_revision:28536; number_of_response:1; }","duration":"128.951345ms","start":"2026-04-02T17:48:52.888826Z","end":"2026-04-02T17:48:53.017778Z","steps":["trace[648716343] 'process raft request' (duration: 128.886733ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:53.017783Z","caller":"traceutil/trace.go:171","msg":"trace[591688555] linearizableReadLoop","detail":"{readStateIndex:29501; appliedIndex:29500; }","duration":"163.954417ms","start":"2026-04-02T17:48:52.85377Z","end":"2026-04-02T17:48:53.017724Z","steps":["trace[591688555] 'read index received' (duration: 2.838652ms)","trace[591688555] 'applied index is now lower than readState.Index' (duration: 161.110545ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:53.017826Z","caller":"traceutil/trace.go:171","msg":"trace[494520005] transaction","detail":"{read_only:false; response_revision:28535; number_of_response:1; }","duration":"161.991631ms","start":"2026-04-02T17:48:52.855798Z","end":"2026-04-02T17:48:53.01779Z","steps":["trace[494520005] 'process raft request' (duration: 161.831708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:53.01796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.156121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-02T17:48:53.018022Z","caller":"traceutil/trace.go:171","msg":"trace[1292993775] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:28536; }","duration":"164.266103ms","start":"2026-04-02T17:48:52.853738Z","end":"2026-04-02T17:48:53.018004Z","steps":["trace[1292993775] 'agreement among raft nodes before linearized reading' (duration: 164.1231ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:53.234382Z","caller":"traceutil/trace.go:171","msg":"trace[1204807164] transaction","detail":"{read_only:false; response_revision:28537; number_of_response:1; }","duration":"118.909387ms","start":"2026-04-02T17:48:53.115436Z","end":"2026-04-02T17:48:53.234346Z","steps":["trace[1204807164] 'process raft request' (duration: 89.034629ms)","trace[1204807164] 'compare' (duration: 29.689065ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:53.358549Z","caller":"traceutil/trace.go:171","msg":"trace[914242877] transaction","detail":"{read_only:false; response_revision:28538; number_of_response:1; }","duration":"242.929999ms","start":"2026-04-02T17:48:53.115586Z","end":"2026-04-02T17:48:53.358516Z","steps":["trace[914242877] 'process raft request' (duration: 219.277797ms)","trace[914242877] 'compare' (duration: 23.413347ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:53.358989Z","caller":"traceutil/trace.go:171","msg":"trace[2017573493] transaction","detail":"{read_only:false; response_revision:28539; number_of_response:1; }","duration":"241.547124ms","start":"2026-04-02T17:48:53.117402Z","end":"2026-04-02T17:48:53.358949Z","steps":["trace[2017573493] 'process raft request' (duration: 241.055465ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:05.707938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25234} {"level":"info","ts":"2026-04-02T17:49:05.826971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25234,"took":"115.170568ms","hash":2119066881,"current-db-size-bytes":51007488,"current-db-size":"51 MB","current-db-size-in-use-bytes":43393024,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-02T17:49:05.827046Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2119066881,"revision":25234,"compact-revision":22899} {"level":"warn","ts":"2026-04-02T17:49:09.148762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.998496ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-02T17:49:09.352395Z","caller":"traceutil/trace.go:171","msg":"trace[1687754835] transaction","detail":"{read_only:false; response_revision:28652; number_of_response:1; }","duration":"264.631774ms","start":"2026-04-02T17:49:09.087733Z","end":"2026-04-02T17:49:09.352365Z","steps":["trace[1687754835] 'process raft request' (duration: 239.136089ms)","trace[1687754835] 'compare' (duration: 25.312232ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:09.419806Z","caller":"traceutil/trace.go:171","msg":"trace[1596061814] transaction","detail":"{read_only:false; response_revision:28653; number_of_response:1; }","duration":"324.929768ms","start":"2026-04-02T17:49:09.094819Z","end":"2026-04-02T17:49:09.419749Z","steps":["trace[1596061814] 'process raft request' (duration: 324.652952ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:09.419848Z","caller":"traceutil/trace.go:171","msg":"trace[306691722] transaction","detail":"{read_only:false; response_revision:28654; number_of_response:1; }","duration":"269.631507ms","start":"2026-04-02T17:49:09.150181Z","end":"2026-04-02T17:49:09.419813Z","steps":["trace[306691722] 'process raft request' (duration: 269.437863ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:09.419981Z","caller":"traceutil/trace.go:171","msg":"trace[250518038] linearizableReadLoop","detail":"{readStateIndex:29625; appliedIndex:29623; }","duration":"289.449138ms","start":"2026-04-02T17:49:09.130497Z","end":"2026-04-02T17:49:09.419946Z","steps":["trace[250518038] 'read index received' (duration: 196.407652ms)","trace[250518038] 'applied index is now lower than readState.Index' (duration: 93.038616ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:09.420048Z","caller":"traceutil/trace.go:171","msg":"trace[720626120] transaction","detail":"{read_only:false; response_revision:28655; number_of_response:1; }","duration":"123.683896ms","start":"2026-04-02T17:49:09.296305Z","end":"2026-04-02T17:49:09.419989Z","steps":["trace[720626120] 'process raft request' (duration: 123.388241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:09.420153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:09.094806Z","time spent":"325.106191ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:49:09.420223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.938118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:49:09.42027Z","caller":"traceutil/trace.go:171","msg":"trace[1639500379] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:28655; }","duration":"208.008459ms","start":"2026-04-02T17:49:09.212246Z","end":"2026-04-02T17:49:09.420255Z","steps":["trace[1639500379] 'agreement among raft nodes before linearized reading' (duration: 207.851386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:09.420279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.748523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:49:09.420356Z","caller":"traceutil/trace.go:171","msg":"trace[794053170] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:28655; }","duration":"289.901196ms","start":"2026-04-02T17:49:09.13044Z","end":"2026-04-02T17:49:09.420341Z","steps":["trace[794053170] 'agreement among raft nodes before linearized reading' (duration: 289.641761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:09.657677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.105646ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:49:09.657822Z","caller":"traceutil/trace.go:171","msg":"trace[1292111972] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:28655; }","duration":"144.2652ms","start":"2026-04-02T17:49:09.513537Z","end":"2026-04-02T17:49:09.657802Z","steps":["trace[1292111972] 'range keys from in-memory index tree' (duration: 144.094296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:09.658542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.376714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-02T17:49:09.658693Z","caller":"traceutil/trace.go:171","msg":"trace[1168950059] transaction","detail":"{read_only:false; response_revision:28656; number_of_response:1; }","duration":"226.814829ms","start":"2026-04-02T17:49:09.431846Z","end":"2026-04-02T17:49:09.658661Z","steps":["trace[1168950059] 'process raft request' (duration: 61.13631ms)","trace[1168950059] 'compare' (duration: 164.573618ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:09.659116Z","caller":"traceutil/trace.go:171","msg":"trace[595943402] linearizableReadLoop","detail":"{readStateIndex:29629; appliedIndex:29627; }","duration":"209.987405ms","start":"2026-04-02T17:49:09.449114Z","end":"2026-04-02T17:49:09.659102Z","steps":["trace[595943402] 'read index received' (duration: 44.02287ms)","trace[595943402] 'applied index is now lower than readState.Index' (duration: 165.962945ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:09.65924Z","caller":"traceutil/trace.go:171","msg":"trace[1667767750] transaction","detail":"{read_only:false; response_revision:28657; number_of_response:1; }","duration":"226.453272ms","start":"2026-04-02T17:49:09.432772Z","end":"2026-04-02T17:49:09.659226Z","steps":["trace[1667767750] 'process raft request' (duration: 226.185927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:09.659325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.051512ms","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-02T17:49:09.659379Z","caller":"traceutil/trace.go:171","msg":"trace[413418777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28657; }","duration":"206.131434ms","start":"2026-04-02T17:49:09.453234Z","end":"2026-04-02T17:49:09.659365Z","steps":["trace[413418777] 'agreement among raft nodes before linearized reading' (duration: 206.037502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:09.659537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.419513ms","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-02T17:49:09.659571Z","caller":"traceutil/trace.go:171","msg":"trace[327658810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28657; }","duration":"210.473584ms","start":"2026-04-02T17:49:09.449088Z","end":"2026-04-02T17:49:09.659562Z","steps":["trace[327658810] 'agreement among raft nodes before linearized reading' (duration: 210.411063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:09.660141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.080918ms","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-02T17:49:09.660205Z","caller":"traceutil/trace.go:171","msg":"trace[1740978305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28657; }","duration":"202.17148ms","start":"2026-04-02T17:49:09.458016Z","end":"2026-04-02T17:49:09.660187Z","steps":["trace[1740978305] 'agreement among raft nodes before linearized reading' (duration: 202.057078ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:09.922136Z","caller":"traceutil/trace.go:171","msg":"trace[2077366629] transaction","detail":"{read_only:false; response_revision:28658; number_of_response:1; }","duration":"257.321387ms","start":"2026-04-02T17:49:09.664788Z","end":"2026-04-02T17:49:09.92211Z","steps":["trace[2077366629] 'process raft request' (duration: 257.101273ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:10.045718Z","caller":"traceutil/trace.go:171","msg":"trace[1442471517] transaction","detail":"{read_only:false; response_revision:28659; number_of_response:1; }","duration":"378.579069ms","start":"2026-04-02T17:49:09.667112Z","end":"2026-04-02T17:49:10.045691Z","steps":["trace[1442471517] 'process raft request' (duration: 376.904827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:10.045916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:09.667092Z","time spent":"378.729892ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:49:10.045987Z","caller":"traceutil/trace.go:171","msg":"trace[425150119] transaction","detail":"{read_only:false; response_revision:28660; number_of_response:1; }","duration":"378.689901ms","start":"2026-04-02T17:49:09.667262Z","end":"2026-04-02T17:49:10.045952Z","steps":["trace[425150119] 'process raft request' (duration: 378.334334ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:10.046159Z","caller":"traceutil/trace.go:171","msg":"trace[185358678] transaction","detail":"{read_only:false; response_revision:28661; number_of_response:1; }","duration":"378.709831ms","start":"2026-04-02T17:49:09.667379Z","end":"2026-04-02T17:49:10.046088Z","steps":["trace[185358678] 'process raft request' (duration: 378.478617ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:10.046305Z","caller":"traceutil/trace.go:171","msg":"trace[1200886786] linearizableReadLoop","detail":"{readStateIndex:29634; appliedIndex:29629; }","duration":"281.968487ms","start":"2026-04-02T17:49:09.764303Z","end":"2026-04-02T17:49:10.046271Z","steps":["trace[1200886786] 'read index received' (duration: 157.625038ms)","trace[1200886786] 'applied index is now lower than readState.Index' (duration: 124.340619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:49:10.046434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.13058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-02T17:49:10.04649Z","caller":"traceutil/trace.go:171","msg":"trace[806782301] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:28663; }","duration":"282.230341ms","start":"2026-04-02T17:49:09.764247Z","end":"2026-04-02T17:49:10.046477Z","steps":["trace[806782301] 'agreement among raft nodes before linearized reading' (duration: 282.106299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:10.046525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:09.667372Z","time spent":"378.942805ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:49:10.046622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.274782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-02T17:49:10.046674Z","caller":"traceutil/trace.go:171","msg":"trace[192526872] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28663; }","duration":"105.380165ms","start":"2026-04-02T17:49:09.941279Z","end":"2026-04-02T17:49:10.046659Z","steps":["trace[192526872] 'agreement among raft nodes before linearized reading' (duration: 105.188601ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:10.046768Z","caller":"traceutil/trace.go:171","msg":"trace[843566382] transaction","detail":"{read_only:false; response_revision:28662; number_of_response:1; }","duration":"379.260012ms","start":"2026-04-02T17:49:09.667417Z","end":"2026-04-02T17:49:10.046677Z","steps":["trace[843566382] 'process raft request' (duration: 378.564999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:10.046882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:09.667255Z","time spent":"378.851855ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:49:10.047054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:09.667412Z","time spent":"379.502687ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:49:10.295284Z","caller":"traceutil/trace.go:171","msg":"trace[1586067409] transaction","detail":"{read_only:false; response_revision:28664; number_of_response:1; }","duration":"241.128575ms","start":"2026-04-02T17:49:10.054127Z","end":"2026-04-02T17:49:10.295256Z","steps":["trace[1586067409] 'process raft request' (duration: 240.884371ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:10.351038Z","caller":"traceutil/trace.go:171","msg":"trace[455105682] transaction","detail":"{read_only:false; response_revision:28665; number_of_response:1; }","duration":"242.742707ms","start":"2026-04-02T17:49:10.108265Z","end":"2026-04-02T17:49:10.351008Z","steps":["trace[455105682] 'process raft request' (duration: 242.280068ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:10.668269Z","caller":"traceutil/trace.go:171","msg":"trace[550370257] transaction","detail":"{read_only:false; response_revision:28666; number_of_response:1; }","duration":"122.780308ms","start":"2026-04-02T17:49:10.545461Z","end":"2026-04-02T17:49:10.668242Z","steps":["trace[550370257] 'process raft request' (duration: 122.578305ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:10.849526Z","caller":"traceutil/trace.go:171","msg":"trace[1840106094] transaction","detail":"{read_only:false; response_revision:28667; number_of_response:1; }","duration":"197.626435ms","start":"2026-04-02T17:49:10.651873Z","end":"2026-04-02T17:49:10.849499Z","steps":["trace[1840106094] 'process raft request' (duration: 125.163783ms)","trace[1840106094] 'compare' (duration: 72.328379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:49:11.093299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.344585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-02T17:49:11.093457Z","caller":"traceutil/trace.go:171","msg":"trace[761668231] transaction","detail":"{read_only:false; response_revision:28668; number_of_response:1; }","duration":"158.650238ms","start":"2026-04-02T17:49:10.934781Z","end":"2026-04-02T17:49:11.093431Z","steps":["trace[761668231] 'process raft request' (duration: 16.042679ms)","trace[761668231] 'compare' (duration: 142.157491ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:11.554238Z","caller":"traceutil/trace.go:171","msg":"trace[1414803685] transaction","detail":"{read_only:false; response_revision:28669; number_of_response:1; }","duration":"194.668809ms","start":"2026-04-02T17:49:11.359534Z","end":"2026-04-02T17:49:11.554203Z","steps":["trace[1414803685] 'process raft request' (duration: 194.444735ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:11.624243Z","caller":"traceutil/trace.go:171","msg":"trace[1923386200] linearizableReadLoop","detail":"{readStateIndex:29643; appliedIndex:29640; }","duration":"109.884248ms","start":"2026-04-02T17:49:11.514308Z","end":"2026-04-02T17:49:11.624192Z","steps":["trace[1923386200] 'read index received' (duration: 39.756951ms)","trace[1923386200] 'applied index is now lower than readState.Index' (duration: 70.126157ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:11.624297Z","caller":"traceutil/trace.go:171","msg":"trace[1475830205] transaction","detail":"{read_only:false; response_revision:28670; number_of_response:1; }","duration":"198.213335ms","start":"2026-04-02T17:49:11.426036Z","end":"2026-04-02T17:49:11.624249Z","steps":["trace[1475830205] 'process raft request' (duration: 194.257951ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:11.624338Z","caller":"traceutil/trace.go:171","msg":"trace[1979176019] transaction","detail":"{read_only:false; response_revision:28671; number_of_response:1; }","duration":"198.112334ms","start":"2026-04-02T17:49:11.426174Z","end":"2026-04-02T17:49:11.624286Z","steps":["trace[1979176019] 'process raft request' (duration: 197.960771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:11.624404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.077262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:49:11.62445Z","caller":"traceutil/trace.go:171","msg":"trace[1446986594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28671; }","duration":"110.217636ms","start":"2026-04-02T17:49:11.514221Z","end":"2026-04-02T17:49:11.624439Z","steps":["trace[1446986594] 'agreement among raft nodes before linearized reading' (duration: 110.108754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:21.281108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.670556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:49:21.281247Z","caller":"traceutil/trace.go:171","msg":"trace[1040062508] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:28741; }","duration":"115.84254ms","start":"2026-04-02T17:49:21.16535Z","end":"2026-04-02T17:49:21.281192Z","steps":["trace[1040062508] 'count revisions from in-memory index tree' (duration: 115.583424ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:21.838527Z","caller":"traceutil/trace.go:171","msg":"trace[22223727] linearizableReadLoop","detail":"{readStateIndex:29718; appliedIndex:29716; }","duration":"149.438665ms","start":"2026-04-02T17:49:21.689054Z","end":"2026-04-02T17:49:21.838492Z","steps":["trace[22223727] 'read index received' (duration: 44.906447ms)","trace[22223727] 'applied index is now lower than readState.Index' (duration: 104.530588ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:21.838574Z","caller":"traceutil/trace.go:171","msg":"trace[599648611] transaction","detail":"{read_only:false; response_revision:28745; number_of_response:1; }","duration":"127.035518ms","start":"2026-04-02T17:49:21.711494Z","end":"2026-04-02T17:49:21.83853Z","steps":["trace[599648611] 'process raft request' (duration: 126.898596ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:21.838642Z","caller":"traceutil/trace.go:171","msg":"trace[335618091] transaction","detail":"{read_only:false; response_revision:28744; number_of_response:1; }","duration":"170.790085ms","start":"2026-04-02T17:49:21.667786Z","end":"2026-04-02T17:49:21.838576Z","steps":["trace[335618091] 'process raft request' (duration: 170.403648ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:21.83867Z","caller":"traceutil/trace.go:171","msg":"trace[723588751] transaction","detail":"{read_only:false; response_revision:28746; number_of_response:1; }","duration":"127.019508ms","start":"2026-04-02T17:49:21.711613Z","end":"2026-04-02T17:49:21.838632Z","steps":["trace[723588751] 'process raft request' (duration: 126.833395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:21.838767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.630639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:49:21.838823Z","caller":"traceutil/trace.go:171","msg":"trace[1326245073] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:28746; }","duration":"149.802942ms","start":"2026-04-02T17:49:21.689004Z","end":"2026-04-02T17:49:21.838807Z","steps":["trace[1326245073] 'agreement among raft nodes before linearized reading' (duration: 149.633839ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:22.158143Z","caller":"traceutil/trace.go:171","msg":"trace[243486310] transaction","detail":"{read_only:false; response_revision:28747; number_of_response:1; }","duration":"124.027393ms","start":"2026-04-02T17:49:22.034092Z","end":"2026-04-02T17:49:22.15812Z","steps":["trace[243486310] 'process raft request' (duration: 123.593254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:22.50354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.369299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-02T17:49:22.503885Z","caller":"traceutil/trace.go:171","msg":"trace[8447580] linearizableReadLoop","detail":"{readStateIndex:29725; appliedIndex:29721; }","duration":"268.286242ms","start":"2026-04-02T17:49:22.235579Z","end":"2026-04-02T17:49:22.503865Z","steps":["trace[8447580] 'read index received' (duration: 5.211077ms)","trace[8447580] 'applied index is now lower than readState.Index' (duration: 263.074065ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:22.503901Z","caller":"traceutil/trace.go:171","msg":"trace[547663748] transaction","detail":"{read_only:false; response_revision:28749; number_of_response:1; }","duration":"339.594181ms","start":"2026-04-02T17:49:22.164262Z","end":"2026-04-02T17:49:22.503856Z","steps":["trace[547663748] 'process raft request' (duration: 192.796515ms)","trace[547663748] 'compare' (duration: 146.172115ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:22.504008Z","caller":"traceutil/trace.go:171","msg":"trace[948422926] transaction","detail":"{read_only:false; response_revision:28750; number_of_response:1; }","duration":"339.687044ms","start":"2026-04-02T17:49:22.164309Z","end":"2026-04-02T17:49:22.503996Z","steps":["trace[948422926] 'process raft request' (duration: 339.347817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:22.504087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:22.164285Z","time spent":"339.759486ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:49:22.504107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:22.164238Z","time spent":"339.755875ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:49:22.504128Z","caller":"traceutil/trace.go:171","msg":"trace[968646554] transaction","detail":"{read_only:false; response_revision:28752; number_of_response:1; }","duration":"105.160621ms","start":"2026-04-02T17:49:22.398944Z","end":"2026-04-02T17:49:22.504105Z","steps":["trace[968646554] 'process raft request' (duration: 104.884045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:22.50447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.88382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-02T17:49:22.504517Z","caller":"traceutil/trace.go:171","msg":"trace[743588350] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28752; }","duration":"152.928491ms","start":"2026-04-02T17:49:22.351574Z","end":"2026-04-02T17:49:22.504502Z","steps":["trace[743588350] 'agreement among raft nodes before linearized reading' (duration: 152.788748ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:22.504627Z","caller":"traceutil/trace.go:171","msg":"trace[612932111] transaction","detail":"{read_only:false; response_revision:28751; number_of_response:1; }","duration":"340.275954ms","start":"2026-04-02T17:49:22.164308Z","end":"2026-04-02T17:49:22.504584Z","steps":["trace[612932111] 'process raft request' (duration: 339.456229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:22.504814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:22.164272Z","time spent":"340.452378ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:49:22.505041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.536107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-02T17:49:22.505225Z","caller":"traceutil/trace.go:171","msg":"trace[740180362] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:28752; }","duration":"269.669217ms","start":"2026-04-02T17:49:22.235529Z","end":"2026-04-02T17:49:22.505198Z","steps":["trace[740180362] 'agreement among raft nodes before linearized reading' (duration: 268.502337ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:36.413867Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T17:49:36.419271Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-02T17:49:36.419365Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-02T17:54:05.714456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28635} {"level":"info","ts":"2026-04-02T17:54:05.870007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28635,"took":"149.7695ms","hash":3485679217,"current-db-size-bytes":53538816,"current-db-size":"54 MB","current-db-size-in-use-bytes":36036608,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-02T17:54:05.870076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3485679217,"revision":28635,"compact-revision":25234} {"level":"info","ts":"2026-04-02T17:59:05.721223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31534} {"level":"info","ts":"2026-04-02T17:59:05.879506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31534,"took":"152.426909ms","hash":2101269844,"current-db-size-bytes":53538816,"current-db-size":"54 MB","current-db-size-in-use-bytes":30326784,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-02T17:59:05.879573Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2101269844,"revision":31534,"compact-revision":28635} {"level":"info","ts":"2026-04-02T18:04:05.728854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33755} {"level":"info","ts":"2026-04-02T18:04:05.847877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33755,"took":"114.126891ms","hash":4238268267,"current-db-size-bytes":53538816,"current-db-size":"54 MB","current-db-size-in-use-bytes":30392320,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-02T18:04:05.847964Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4238268267,"revision":33755,"compact-revision":31534} {"level":"info","ts":"2026-04-02T18:09:05.734849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36049} {"level":"info","ts":"2026-04-02T18:09:05.859765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36049,"took":"119.264308ms","hash":3787615909,"current-db-size-bytes":53538816,"current-db-size":"54 MB","current-db-size-in-use-bytes":32161792,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-02T18:09:05.859839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3787615909,"revision":36049,"compact-revision":33755} {"level":"info","ts":"2026-04-02T18:09:53.211077Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T18:09:53.218326Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-02T18:09:53.21845Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-02T18:14:05.745601Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38404} {"level":"info","ts":"2026-04-02T18:14:05.911307Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38404,"took":"161.601034ms","hash":1665184217,"current-db-size-bytes":54865920,"current-db-size":"55 MB","current-db-size-in-use-bytes":31522816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-02T18:14:05.911411Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1665184217,"revision":38404,"compact-revision":36049} {"level":"info","ts":"2026-04-02T18:19:05.756764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40631} {"level":"info","ts":"2026-04-02T18:19:05.883495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40631,"took":"120.460788ms","hash":2084422761,"current-db-size-bytes":54865920,"current-db-size":"55 MB","current-db-size-in-use-bytes":32219136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-02T18:19:05.883564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2084422761,"revision":40631,"compact-revision":38404}