{"level":"warn","ts":"2026-10-09T10:56:50.095549Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T10:56:50.095633Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.164:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.164:2380","--initial-cluster=instance=https://162.253.55.164:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.164:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.164: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-10-09T10:56:50.095693Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T10:56:50.0957Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.164:2380"]} {"level":"info","ts":"2026-10-09T10:56:50.095715Z","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-10-09T10:56:50.096318Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"]} {"level":"info","ts":"2026-10-09T10:56:50.096391Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.164:2380"],"listen-peer-urls":["https://162.253.55.164:2380"],"advertise-client-urls":["https://162.253.55.164:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.164: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-10-09T10:56:50.100404Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.807961ms"} {"level":"info","ts":"2026-10-09T10:56:50.106918Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"777352cb93f4c05d","cluster-id":"ebb6470dab83b2e8"} {"level":"info","ts":"2026-10-09T10:56:50.106988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T10:56:50.107008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became follower at term 0"} {"level":"info","ts":"2026-10-09T10:56:50.107016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 777352cb93f4c05d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T10:56:50.107026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became follower at term 1"} {"level":"info","ts":"2026-10-09T10:56:50.107073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=(8607314347149279325)"} {"level":"warn","ts":"2026-10-09T10:56:50.113717Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T10:56:50.115897Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T10:56:50.119624Z","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-10-09T10:56:50.121975Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"777352cb93f4c05d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T10:56:50.12216Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"777352cb93f4c05d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T10:56:50.122272Z","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-10-09T10:56:50.122327Z","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-10-09T10:56:50.122335Z","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-10-09T10:56:50.123874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=(8607314347149279325)"} {"level":"info","ts":"2026-10-09T10:56:50.125126Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ebb6470dab83b2e8","local-member-id":"777352cb93f4c05d","added-peer-id":"777352cb93f4c05d","added-peer-peer-urls":["https://162.253.55.164:2380"]} {"level":"info","ts":"2026-10-09T10:56:50.127931Z","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-10-09T10:56:50.128158Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.164:2380"} {"level":"info","ts":"2026-10-09T10:56:50.128257Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.164:2380"} {"level":"info","ts":"2026-10-09T10:56:50.128557Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T10:56:50.128455Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"777352cb93f4c05d","initial-advertise-peer-urls":["https://162.253.55.164:2380"],"listen-peer-urls":["https://162.253.55.164:2380"],"advertise-client-urls":["https://162.253.55.164:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T10:56:51.008114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T10:56:51.008203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T10:56:51.008225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d received MsgPreVoteResp from 777352cb93f4c05d at term 1"} {"level":"info","ts":"2026-10-09T10:56:51.008244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became candidate at term 2"} {"level":"info","ts":"2026-10-09T10:56:51.008254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d received MsgVoteResp from 777352cb93f4c05d at term 2"} {"level":"info","ts":"2026-10-09T10:56:51.008269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became leader at term 2"} {"level":"info","ts":"2026-10-09T10:56:51.008288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 777352cb93f4c05d elected leader 777352cb93f4c05d at term 2"} {"level":"info","ts":"2026-10-09T10:56:51.009697Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T10:56:51.01093Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T10:56:51.010918Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"777352cb93f4c05d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.164:2379]}","request-path":"/0/members/777352cb93f4c05d/attributes","cluster-id":"ebb6470dab83b2e8","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T10:56:51.011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T10:56:51.011338Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T10:56:51.01151Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T10:56:51.011692Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ebb6470dab83b2e8","local-member-id":"777352cb93f4c05d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T10:56:51.011851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T10:56:51.011896Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T10:56:51.014424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.164:2379"} {"level":"info","ts":"2026-10-09T10:56:51.014859Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T10:56:52.649201Z","caller":"traceutil/trace.go:171","msg":"trace[1932749852] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"105.386231ms","start":"2026-10-09T10:56:52.543785Z","end":"2026-10-09T10:56:52.649171Z","steps":["trace[1932749852] 'process raft request' (duration: 97.945679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:56:59.145657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.086228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T10:56:59.145786Z","caller":"traceutil/trace.go:171","msg":"trace[1688900901] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:328; }","duration":"151.333933ms","start":"2026-10-09T10:56:58.994426Z","end":"2026-10-09T10:56:59.14576Z","steps":["trace[1688900901] 'range keys from in-memory index tree' (duration: 150.981206ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:57:33.861189Z","caller":"traceutil/trace.go:171","msg":"trace[563413737] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"108.620282ms","start":"2026-10-09T10:57:33.752545Z","end":"2026-10-09T10:57:33.861165Z","steps":["trace[563413737] 'process raft request' (duration: 108.438498ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:57:41.455947Z","caller":"traceutil/trace.go:171","msg":"trace[1616685393] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"362.063279ms","start":"2026-10-09T10:57:41.093857Z","end":"2026-10-09T10:57:41.45592Z","steps":["trace[1616685393] 'process raft request' (duration: 361.924046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:57:41.456534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:57:41.093828Z","time spent":"362.199001ms","remote":"127.0.0.1:57246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T10:59:12.789576Z","caller":"traceutil/trace.go:171","msg":"trace[2115819845] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"159.175826ms","start":"2026-10-09T10:59:12.630359Z","end":"2026-10-09T10:59:12.789535Z","steps":["trace[2115819845] 'process raft request' (duration: 158.938032ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:00:01.65977Z","caller":"traceutil/trace.go:171","msg":"trace[755063110] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2281; }","duration":"197.371043ms","start":"2026-10-09T11:00:01.462365Z","end":"2026-10-09T11:00:01.659736Z","steps":["trace[755063110] 'read index received' (duration: 197.167919ms)","trace[755063110] 'applied index is now lower than readState.Index' (duration: 202.164µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:00:01.659889Z","caller":"traceutil/trace.go:171","msg":"trace[1441847926] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"211.606882ms","start":"2026-10-09T11:00:01.448242Z","end":"2026-10-09T11:00:01.659848Z","steps":["trace[1441847926] 'process raft request' (duration: 211.359298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:00:01.659992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.612888ms","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-10-09T11:00:01.660037Z","caller":"traceutil/trace.go:171","msg":"trace[1726871308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2200; }","duration":"197.675009ms","start":"2026-10-09T11:00:01.462348Z","end":"2026-10-09T11:00:01.660024Z","steps":["trace[1726871308] 'agreement among raft nodes before linearized reading' (duration: 197.530026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:00:01.695289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.116852ms","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-10-09T11:00:01.69539Z","caller":"traceutil/trace.go:171","msg":"trace[898583415] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2203; }","duration":"144.279305ms","start":"2026-10-09T11:00:01.551089Z","end":"2026-10-09T11:00:01.695368Z","steps":["trace[898583415] 'agreement among raft nodes before linearized reading' (duration: 143.839776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:00:07.345076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.624833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T11:00:07.34522Z","caller":"traceutil/trace.go:171","msg":"trace[1930538977] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"281.257034ms","start":"2026-10-09T11:00:07.063941Z","end":"2026-10-09T11:00:07.345198Z","steps":["trace[1930538977] 'process raft request' (duration: 126.104711ms)","trace[1930538977] 'compare' (duration: 154.291966ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:00:43.574571Z","caller":"traceutil/trace.go:171","msg":"trace[10826208] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"117.330305ms","start":"2026-10-09T11:00:43.457218Z","end":"2026-10-09T11:00:43.574548Z","steps":["trace[10826208] 'process raft request' (duration: 117.209053ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:00:43.57535Z","caller":"traceutil/trace.go:171","msg":"trace[134014024] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"115.595712ms","start":"2026-10-09T11:00:43.459734Z","end":"2026-10-09T11:00:43.57533Z","steps":["trace[134014024] 'process raft request' (duration: 115.51365ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:05:57.383471Z","caller":"traceutil/trace.go:171","msg":"trace[891991565] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"149.486536ms","start":"2026-10-09T11:05:57.233957Z","end":"2026-10-09T11:05:57.383444Z","steps":["trace[891991565] 'process raft request' (duration: 149.283132ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:05:57.61483Z","caller":"traceutil/trace.go:171","msg":"trace[1681282032] linearizableReadLoop","detail":"{readStateIndex:5286; appliedIndex:5285; }","duration":"212.358126ms","start":"2026-10-09T11:05:57.402449Z","end":"2026-10-09T11:05:57.614807Z","steps":["trace[1681282032] 'read index received' (duration: 212.157612ms)","trace[1681282032] 'applied index is now lower than readState.Index' (duration: 199.454µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:05:57.614977Z","caller":"traceutil/trace.go:171","msg":"trace[1970740927] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"364.92348ms","start":"2026-10-09T11:05:57.250015Z","end":"2026-10-09T11:05:57.614939Z","steps":["trace[1970740927] 'process raft request' (duration: 364.633894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:05:57.615049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.582051ms","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-10-09T11:05:57.615082Z","caller":"traceutil/trace.go:171","msg":"trace[1926294211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5096; }","duration":"212.659332ms","start":"2026-10-09T11:05:57.402414Z","end":"2026-10-09T11:05:57.615074Z","steps":["trace[1926294211] 'agreement among raft nodes before linearized reading' (duration: 212.513229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:05:57.615174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:05:57.249991Z","time spent":"365.079283ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:06:00.186717Z","caller":"traceutil/trace.go:171","msg":"trace[215827699] linearizableReadLoop","detail":"{readStateIndex:5413; appliedIndex:5412; }","duration":"113.265367ms","start":"2026-10-09T11:06:00.073431Z","end":"2026-10-09T11:06:00.186697Z","steps":["trace[215827699] 'read index received' (duration: 113.033313ms)","trace[215827699] 'applied index is now lower than readState.Index' (duration: 231.384µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:06:00.187321Z","caller":"traceutil/trace.go:171","msg":"trace[1101992530] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"143.108613ms","start":"2026-10-09T11:06:00.044173Z","end":"2026-10-09T11:06:00.187281Z","steps":["trace[1101992530] 'process raft request' (duration: 142.360079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:06:00.187524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.076423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728931"} {"level":"info","ts":"2026-10-09T11:06:00.187606Z","caller":"traceutil/trace.go:171","msg":"trace[1982103314] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5220; }","duration":"114.203585ms","start":"2026-10-09T11:06:00.073384Z","end":"2026-10-09T11:06:00.187588Z","steps":["trace[1982103314] 'agreement among raft nodes before linearized reading' (duration: 113.567733ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:06:15.479026Z","caller":"traceutil/trace.go:171","msg":"trace[1631111341] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"132.439261ms","start":"2026-10-09T11:06:15.346558Z","end":"2026-10-09T11:06:15.478997Z","steps":["trace[1631111341] 'process raft request' (duration: 132.37721ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:06:15.479078Z","caller":"traceutil/trace.go:171","msg":"trace[1966038542] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"135.082932ms","start":"2026-10-09T11:06:15.343958Z","end":"2026-10-09T11:06:15.479041Z","steps":["trace[1966038542] 'process raft request' (duration: 134.789016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:06:22.803138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.578454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:06:22.803241Z","caller":"traceutil/trace.go:171","msg":"trace[915241793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5674; }","duration":"113.667836ms","start":"2026-10-09T11:06:22.689529Z","end":"2026-10-09T11:06:22.803197Z","steps":["trace[915241793] 'range keys from in-memory index tree' (duration: 113.446512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:06:22.803246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.143948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29b3dc1b-2e37-4db8-96ab-7c8ed563b342\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-09T11:06:22.803328Z","caller":"traceutil/trace.go:171","msg":"trace[209699646] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-29b3dc1b-2e37-4db8-96ab-7c8ed563b342; range_end:; response_count:1; response_revision:5674; }","duration":"111.300891ms","start":"2026-10-09T11:06:22.692006Z","end":"2026-10-09T11:06:22.803307Z","steps":["trace[209699646] 'range keys from in-memory index tree' (duration: 110.926584ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:06:22.981044Z","caller":"traceutil/trace.go:171","msg":"trace[184866199] linearizableReadLoop","detail":"{readStateIndex:5883; appliedIndex:5882; }","duration":"130.27877ms","start":"2026-10-09T11:06:22.850736Z","end":"2026-10-09T11:06:22.981014Z","steps":["trace[184866199] 'read index received' (duration: 130.116917ms)","trace[184866199] 'applied index is now lower than readState.Index' (duration: 160.703µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:06:22.981366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.606796ms","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:5"} {"level":"info","ts":"2026-10-09T11:06:22.981363Z","caller":"traceutil/trace.go:171","msg":"trace[40390508] transaction","detail":"{read_only:false; response_revision:5675; number_of_response:1; }","duration":"144.662672ms","start":"2026-10-09T11:06:22.836666Z","end":"2026-10-09T11:06:22.981329Z","steps":["trace[40390508] 'process raft request' (duration: 144.204773ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:06:22.981412Z","caller":"traceutil/trace.go:171","msg":"trace[140013807] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5675; }","duration":"130.712989ms","start":"2026-10-09T11:06:22.850688Z","end":"2026-10-09T11:06:22.981401Z","steps":["trace[140013807] 'agreement among raft nodes before linearized reading' (duration: 130.625567ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:06:51.046544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-10-09T11:06:51.143686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"95.759395ms","hash":2920985564,"current-db-size-bytes":57548800,"current-db-size":"58 MB","current-db-size-in-use-bytes":32698368,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T11:06:51.143754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2920985564,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-10-09T11:07:15.504155Z","caller":"traceutil/trace.go:171","msg":"trace[1787535239] transaction","detail":"{read_only:false; response_revision:6165; number_of_response:1; }","duration":"103.945042ms","start":"2026-10-09T11:07:15.400171Z","end":"2026-10-09T11:07:15.504116Z","steps":["trace[1787535239] 'process raft request' (duration: 51.090889ms)","trace[1787535239] 'compare' (duration: 52.678469ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:11:51.053457Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5925} {"level":"info","ts":"2026-10-09T11:11:51.150838Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5925,"took":"95.661ms","hash":2143819620,"current-db-size-bytes":57548800,"current-db-size":"58 MB","current-db-size-in-use-bytes":25939968,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T11:11:51.150901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2143819620,"revision":5925,"compact-revision":2995} {"level":"info","ts":"2026-10-09T11:12:42.101467Z","caller":"traceutil/trace.go:171","msg":"trace[1044328647] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"243.268752ms","start":"2026-10-09T11:12:41.858168Z","end":"2026-10-09T11:12:42.101437Z","steps":["trace[1044328647] 'process raft request' (duration: 243.087428ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:12:54.719797Z","caller":"traceutil/trace.go:171","msg":"trace[1282956847] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"125.182997ms","start":"2026-10-09T11:12:54.594572Z","end":"2026-10-09T11:12:54.719755Z","steps":["trace[1282956847] 'process raft request' (duration: 63.377768ms)","trace[1282956847] 'compare' (duration: 61.494723ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:12:54.858792Z","caller":"traceutil/trace.go:171","msg":"trace[623294999] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"117.800218ms","start":"2026-10-09T11:12:54.740901Z","end":"2026-10-09T11:12:54.858702Z","steps":["trace[623294999] 'process raft request' (duration: 98.116156ms)","trace[623294999] 'compare' (duration: 19.453538ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:13:04.89643Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000231c.wal"} {"level":"warn","ts":"2026-10-09T11:13:23.478873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.129162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T11:13:23.478957Z","caller":"traceutil/trace.go:171","msg":"trace[1037492063] linearizableReadLoop","detail":"{readStateIndex:9137; appliedIndex:9136; }","duration":"401.971201ms","start":"2026-10-09T11:13:23.076974Z","end":"2026-10-09T11:13:23.478945Z","steps":["trace[1037492063] 'read index received' (duration: 235.666277ms)","trace[1037492063] 'applied index is now lower than readState.Index' (duration: 166.304094ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:13:23.479034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.057323ms","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:5"} {"level":"info","ts":"2026-10-09T11:13:23.479057Z","caller":"traceutil/trace.go:171","msg":"trace[954656163] 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:8827; }","duration":"402.116524ms","start":"2026-10-09T11:13:23.076934Z","end":"2026-10-09T11:13:23.47905Z","steps":["trace[954656163] 'agreement among raft nodes before linearized reading' (duration: 402.043573ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:13:23.479053Z","caller":"traceutil/trace.go:171","msg":"trace[1181629064] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"439.828357ms","start":"2026-10-09T11:13:23.039197Z","end":"2026-10-09T11:13:23.479025Z","steps":["trace[1181629064] 'process raft request' (duration: 273.471981ms)","trace[1181629064] 'compare' (duration: 166.03605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:13:23.479079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:13:23.076915Z","time spent":"402.157254ms","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:13:23.479165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:13:23.039172Z","time spent":"439.935369ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:13:27.006872Z","caller":"traceutil/trace.go:171","msg":"trace[1770738049] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"288.804421ms","start":"2026-10-09T11:13:26.71805Z","end":"2026-10-09T11:13:27.006855Z","steps":["trace[1770738049] 'process raft request' (duration: 198.566506ms)","trace[1770738049] 'compare' (duration: 90.111213ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:13:27.007027Z","caller":"traceutil/trace.go:171","msg":"trace[256057355] transaction","detail":"{read_only:false; response_revision:8846; number_of_response:1; }","duration":"236.326388ms","start":"2026-10-09T11:13:26.770677Z","end":"2026-10-09T11:13:27.007003Z","steps":["trace[256057355] 'process raft request' (duration: 236.153605ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:13:27.05513Z","caller":"traceutil/trace.go:171","msg":"trace[185692058] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"213.524988ms","start":"2026-10-09T11:13:26.84158Z","end":"2026-10-09T11:13:27.055105Z","steps":["trace[185692058] 'process raft request' (duration: 213.384805ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:13:27.075885Z","caller":"traceutil/trace.go:171","msg":"trace[647219455] transaction","detail":"{read_only:false; response_revision:8848; number_of_response:1; }","duration":"103.832494ms","start":"2026-10-09T11:13:26.972017Z","end":"2026-10-09T11:13:27.075849Z","steps":["trace[647219455] 'process raft request' (duration: 103.63698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:13:35.331734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.888081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T11:13:35.331853Z","caller":"traceutil/trace.go:171","msg":"trace[1740664733] transaction","detail":"{read_only:false; response_revision:8891; number_of_response:1; }","duration":"248.169224ms","start":"2026-10-09T11:13:35.083661Z","end":"2026-10-09T11:13:35.33183Z","steps":["trace[1740664733] 'process raft request' (duration: 122.445727ms)","trace[1740664733] 'compare' (duration: 124.33005ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:13:35.343793Z","caller":"traceutil/trace.go:171","msg":"trace[158962363] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"229.390898ms","start":"2026-10-09T11:13:35.114361Z","end":"2026-10-09T11:13:35.343752Z","steps":["trace[158962363] 'process raft request' (duration: 229.169054ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:14:50.323615Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T11:14:50.330765Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T11:14:50.330854Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-09T11:15:05.784961Z","caller":"traceutil/trace.go:171","msg":"trace[1844938033] transaction","detail":"{read_only:false; response_revision:9755; number_of_response:1; }","duration":"126.651023ms","start":"2026-10-09T11:15:05.658285Z","end":"2026-10-09T11:15:05.784936Z","steps":["trace[1844938033] 'process raft request' (duration: 126.50704ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:15:05.87118Z","caller":"traceutil/trace.go:171","msg":"trace[627108007] linearizableReadLoop","detail":"{readStateIndex:10095; appliedIndex:10093; }","duration":"180.187105ms","start":"2026-10-09T11:15:05.690967Z","end":"2026-10-09T11:15:05.871154Z","steps":["trace[627108007] 'read index received' (duration: 93.820683ms)","trace[627108007] 'applied index is now lower than readState.Index' (duration: 86.365492ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:15:05.87126Z","caller":"traceutil/trace.go:171","msg":"trace[1132492632] transaction","detail":"{read_only:false; response_revision:9756; number_of_response:1; }","duration":"210.039479ms","start":"2026-10-09T11:15:05.66118Z","end":"2026-10-09T11:15:05.871219Z","steps":["trace[1132492632] 'process raft request' (duration: 199.063972ms)","trace[1132492632] 'compare' (duration: 10.802554ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:15:05.871319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.338277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:15:05.871353Z","caller":"traceutil/trace.go:171","msg":"trace[1079250420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9756; }","duration":"180.427889ms","start":"2026-10-09T11:15:05.690915Z","end":"2026-10-09T11:15:05.871342Z","steps":["trace[1079250420] 'agreement among raft nodes before linearized reading' (duration: 180.351518ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:16:51.067619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8083} {"level":"info","ts":"2026-10-09T11:16:51.140121Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8083,"took":"70.408579ms","hash":1725852530,"current-db-size-bytes":57548800,"current-db-size":"58 MB","current-db-size-in-use-bytes":28135424,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T11:16:51.140195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1725852530,"revision":8083,"compact-revision":5925} {"level":"info","ts":"2026-10-09T11:20:42.862826Z","caller":"traceutil/trace.go:171","msg":"trace[2076215651] transaction","detail":"{read_only:false; response_revision:12175; number_of_response:1; }","duration":"158.780986ms","start":"2026-10-09T11:20:42.704022Z","end":"2026-10-09T11:20:42.862803Z","steps":["trace[2076215651] 'process raft request' (duration: 158.623713ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:20:42.893386Z","caller":"traceutil/trace.go:171","msg":"trace[1574963973] linearizableReadLoop","detail":"{readStateIndex:12594; appliedIndex:12593; }","duration":"153.852892ms","start":"2026-10-09T11:20:42.739508Z","end":"2026-10-09T11:20:42.893361Z","steps":["trace[1574963973] 'read index received' (duration: 123.277505ms)","trace[1574963973] 'applied index is now lower than readState.Index' (duration: 30.574147ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:20:42.893468Z","caller":"traceutil/trace.go:171","msg":"trace[77321414] transaction","detail":"{read_only:false; response_revision:12176; number_of_response:1; }","duration":"189.2208ms","start":"2026-10-09T11:20:42.704221Z","end":"2026-10-09T11:20:42.893442Z","steps":["trace[77321414] 'process raft request' (duration: 188.988825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:20:42.89357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.040715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:20:42.893646Z","caller":"traceutil/trace.go:171","msg":"trace[93989396] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:12176; }","duration":"154.165678ms","start":"2026-10-09T11:20:42.73946Z","end":"2026-10-09T11:20:42.893626Z","steps":["trace[93989396] 'agreement among raft nodes before linearized reading' (duration: 154.029746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:20:42.893746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.299944ms","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-10-09T11:20:42.893828Z","caller":"traceutil/trace.go:171","msg":"trace[2050913966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12176; }","duration":"125.426437ms","start":"2026-10-09T11:20:42.768382Z","end":"2026-10-09T11:20:42.893808Z","steps":["trace[2050913966] 'agreement among raft nodes before linearized reading' (duration: 125.186172ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:21:51.075357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10476} {"level":"info","ts":"2026-10-09T11:21:51.179948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10476,"took":"90.253242ms","hash":862678823,"current-db-size-bytes":57548800,"current-db-size":"58 MB","current-db-size-in-use-bytes":25780224,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T11:21:51.180027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":862678823,"revision":10476,"compact-revision":8083} {"level":"info","ts":"2026-10-09T11:26:51.083079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12642} {"level":"info","ts":"2026-10-09T11:26:51.163667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12642,"took":"78.133005ms","hash":3037590349,"current-db-size-bytes":57548800,"current-db-size":"58 MB","current-db-size-in-use-bytes":27705344,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T11:26:51.163734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3037590349,"revision":12642,"compact-revision":10476} {"level":"info","ts":"2026-10-09T11:27:22.511529Z","caller":"traceutil/trace.go:171","msg":"trace[309517481] transaction","detail":"{read_only:false; response_revision:14826; number_of_response:1; }","duration":"104.91302ms","start":"2026-10-09T11:27:22.406565Z","end":"2026-10-09T11:27:22.511479Z","steps":["trace[309517481] 'process raft request' (duration: 103.951222ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:22.511701Z","caller":"traceutil/trace.go:171","msg":"trace[557113678] transaction","detail":"{read_only:false; response_revision:14827; number_of_response:1; }","duration":"103.951971ms","start":"2026-10-09T11:27:22.407719Z","end":"2026-10-09T11:27:22.511671Z","steps":["trace[557113678] 'process raft request' (duration: 103.707167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:30.994049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.860372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T11:27:30.994293Z","caller":"traceutil/trace.go:171","msg":"trace[689369284] transaction","detail":"{read_only:false; response_revision:14887; number_of_response:1; }","duration":"411.104718ms","start":"2026-10-09T11:27:30.58313Z","end":"2026-10-09T11:27:30.994234Z","steps":["trace[689369284] 'process raft request' (duration: 281.970291ms)","trace[689369284] 'compare' (duration: 128.623558ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:27:30.994508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:27:30.583111Z","time spent":"411.291412ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:27:30.994524Z","caller":"traceutil/trace.go:171","msg":"trace[1856700256] transaction","detail":"{read_only:false; response_revision:14888; number_of_response:1; }","duration":"411.303252ms","start":"2026-10-09T11:27:30.583184Z","end":"2026-10-09T11:27:30.994488Z","steps":["trace[1856700256] 'process raft request' (duration: 410.952995ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:30.994491Z","caller":"traceutil/trace.go:171","msg":"trace[120494012] linearizableReadLoop","detail":"{readStateIndex:15405; appliedIndex:15403; }","duration":"302.850645ms","start":"2026-10-09T11:27:30.691623Z","end":"2026-10-09T11:27:30.994474Z","steps":["trace[120494012] 'read index received' (duration: 173.471363ms)","trace[120494012] 'applied index is now lower than readState.Index' (duration: 129.377832ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:27:30.994782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.162192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:27:30.99482Z","caller":"traceutil/trace.go:171","msg":"trace[385241980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14888; }","duration":"303.239312ms","start":"2026-10-09T11:27:30.69157Z","end":"2026-10-09T11:27:30.994809Z","steps":["trace[385241980] 'agreement among raft nodes before linearized reading' (duration: 303.169441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:30.994819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:27:30.58316Z","time spent":"411.594537ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:27:30.994847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:27:30.69155Z","time spent":"303.290004ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T11:27:30.994854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.114439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-hxtph\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-10-09T11:27:30.994934Z","caller":"traceutil/trace.go:171","msg":"trace[1934294864] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-hxtph; range_end:; response_count:1; response_revision:14888; }","duration":"189.20384ms","start":"2026-10-09T11:27:30.805712Z","end":"2026-10-09T11:27:30.994916Z","steps":["trace[1934294864] 'agreement among raft nodes before linearized reading' (duration: 189.064258ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:41.266696Z","caller":"traceutil/trace.go:171","msg":"trace[6500945] linearizableReadLoop","detail":"{readStateIndex:15579; appliedIndex:15578; }","duration":"226.416343ms","start":"2026-10-09T11:27:41.040251Z","end":"2026-10-09T11:27:41.266667Z","steps":["trace[6500945] 'read index received' (duration: 226.097547ms)","trace[6500945] 'applied index is now lower than readState.Index' (duration: 317.626µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:27:41.266835Z","caller":"traceutil/trace.go:171","msg":"trace[918981153] transaction","detail":"{read_only:false; response_revision:15059; number_of_response:1; }","duration":"279.559046ms","start":"2026-10-09T11:27:40.987262Z","end":"2026-10-09T11:27:41.266821Z","steps":["trace[918981153] 'process raft request' (duration: 279.201699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:41.267087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.176758ms","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-10-09T11:27:41.26716Z","caller":"traceutil/trace.go:171","msg":"trace[61077853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15059; }","duration":"226.28902ms","start":"2026-10-09T11:27:41.040854Z","end":"2026-10-09T11:27:41.267143Z","steps":["trace[61077853] 'agreement among raft nodes before linearized reading' (duration: 226.114547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:41.267406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.148047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T11:27:41.267449Z","caller":"traceutil/trace.go:171","msg":"trace[121562488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15059; }","duration":"227.248159ms","start":"2026-10-09T11:27:41.040191Z","end":"2026-10-09T11:27:41.267439Z","steps":["trace[121562488] 'agreement among raft nodes before linearized reading' (duration: 227.068786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:41.267502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.742287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-09T11:27:41.267558Z","caller":"traceutil/trace.go:171","msg":"trace[1862251810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15059; }","duration":"104.88074ms","start":"2026-10-09T11:27:41.162664Z","end":"2026-10-09T11:27:41.267545Z","steps":["trace[1862251810] 'agreement among raft nodes before linearized reading' (duration: 104.739187ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:41.426898Z","caller":"traceutil/trace.go:171","msg":"trace[1545023845] transaction","detail":"{read_only:false; response_revision:15060; number_of_response:1; }","duration":"157.448062ms","start":"2026-10-09T11:27:41.269419Z","end":"2026-10-09T11:27:41.426867Z","steps":["trace[1545023845] 'process raft request' (duration: 131.92952ms)","trace[1545023845] 'compare' (duration: 25.378019ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:27:41.43297Z","caller":"traceutil/trace.go:171","msg":"trace[573931730] transaction","detail":"{read_only:false; response_revision:15061; number_of_response:1; }","duration":"162.009479ms","start":"2026-10-09T11:27:41.270929Z","end":"2026-10-09T11:27:41.432938Z","steps":["trace[573931730] 'process raft request' (duration: 161.834835ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:41.433521Z","caller":"traceutil/trace.go:171","msg":"trace[1384255990] linearizableReadLoop","detail":"{readStateIndex:15581; appliedIndex:15579; }","duration":"162.419995ms","start":"2026-10-09T11:27:41.271076Z","end":"2026-10-09T11:27:41.433496Z","steps":["trace[1384255990] 'read index received' (duration: 130.373341ms)","trace[1384255990] 'applied index is now lower than readState.Index' (duration: 32.045674ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:27:41.433587Z","caller":"traceutil/trace.go:171","msg":"trace[375825691] transaction","detail":"{read_only:false; response_revision:15062; number_of_response:1; }","duration":"162.004178ms","start":"2026-10-09T11:27:41.271568Z","end":"2026-10-09T11:27:41.433572Z","steps":["trace[375825691] 'process raft request' (duration: 161.329666ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:41.433754Z","caller":"traceutil/trace.go:171","msg":"trace[1645388528] transaction","detail":"{read_only:false; response_revision:15063; number_of_response:1; }","duration":"162.090369ms","start":"2026-10-09T11:27:41.27165Z","end":"2026-10-09T11:27:41.433741Z","steps":["trace[1645388528] 'process raft request' (duration: 161.63498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:41.434082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.983836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eee7276e-2cfa-4833-b30b-b8f92e1129d3\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-10-09T11:27:41.434146Z","caller":"traceutil/trace.go:171","msg":"trace[1830280834] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eee7276e-2cfa-4833-b30b-b8f92e1129d3; range_end:; response_count:1; response_revision:15064; }","duration":"163.056967ms","start":"2026-10-09T11:27:41.271072Z","end":"2026-10-09T11:27:41.434129Z","steps":["trace[1830280834] 'agreement among raft nodes before linearized reading' (duration: 162.760992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:41.434553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.223765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:630318"} {"level":"info","ts":"2026-10-09T11:27:41.434601Z","caller":"traceutil/trace.go:171","msg":"trace[966145814] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:15064; }","duration":"159.316986ms","start":"2026-10-09T11:27:41.275272Z","end":"2026-10-09T11:27:41.434589Z","steps":["trace[966145814] 'agreement among raft nodes before linearized reading' (duration: 158.612093ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:41.434893Z","caller":"traceutil/trace.go:171","msg":"trace[727089906] transaction","detail":"{read_only:false; response_revision:15064; number_of_response:1; }","duration":"162.234432ms","start":"2026-10-09T11:27:41.272649Z","end":"2026-10-09T11:27:41.434883Z","steps":["trace[727089906] 'process raft request' (duration: 160.804274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:41.434905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.674922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T11:27:41.435452Z","caller":"traceutil/trace.go:171","msg":"trace[1980997344] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:15064; }","duration":"125.765154ms","start":"2026-10-09T11:27:41.309173Z","end":"2026-10-09T11:27:41.434939Z","steps":["trace[1980997344] 'agreement among raft nodes before linearized reading' (duration: 125.656522ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:31:51.090204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14664} {"level":"info","ts":"2026-10-09T11:31:51.177028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14664,"took":"82.641873ms","hash":3672019960,"current-db-size-bytes":57548800,"current-db-size":"58 MB","current-db-size-in-use-bytes":32444416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T11:31:51.177105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672019960,"revision":14664,"compact-revision":12642} {"level":"warn","ts":"2026-10-09T11:35:06.045757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.898346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:35:06.045867Z","caller":"traceutil/trace.go:171","msg":"trace[90688026] linearizableReadLoop","detail":"{readStateIndex:19307; appliedIndex:19306; }","duration":"260.377608ms","start":"2026-10-09T11:35:05.785473Z","end":"2026-10-09T11:35:06.04585Z","steps":["trace[90688026] 'read index received' (duration: 110.247957ms)","trace[90688026] 'applied index is now lower than readState.Index' (duration: 150.128091ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:35:06.045901Z","caller":"traceutil/trace.go:171","msg":"trace[555847449] transaction","detail":"{read_only:false; response_revision:18670; number_of_response:1; }","duration":"288.663203ms","start":"2026-10-09T11:35:05.757201Z","end":"2026-10-09T11:35:06.045864Z","steps":["trace[555847449] 'process raft request' (duration: 138.573343ms)","trace[555847449] 'compare' (duration: 149.754833ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:35:06.045986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.50886ms","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-10-09T11:35:06.046021Z","caller":"traceutil/trace.go:171","msg":"trace[194499718] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18670; }","duration":"260.545011ms","start":"2026-10-09T11:35:05.785465Z","end":"2026-10-09T11:35:06.04601Z","steps":["trace[194499718] 'agreement among raft nodes before linearized reading' (duration: 260.477289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:35:06.046165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.309472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T11:35:06.046281Z","caller":"traceutil/trace.go:171","msg":"trace[788753887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18670; }","duration":"227.463435ms","start":"2026-10-09T11:35:05.81877Z","end":"2026-10-09T11:35:06.046234Z","steps":["trace[788753887] 'agreement among raft nodes before linearized reading' (duration: 227.177289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:35:06.046468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.611487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T11:35:06.046569Z","caller":"traceutil/trace.go:171","msg":"trace[1095416863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18670; }","duration":"162.74244ms","start":"2026-10-09T11:35:05.883804Z","end":"2026-10-09T11:35:06.046546Z","steps":["trace[1095416863] 'agreement among raft nodes before linearized reading' (duration: 162.485955ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:35:06.367396Z","caller":"traceutil/trace.go:171","msg":"trace[623372273] transaction","detail":"{read_only:false; response_revision:18673; number_of_response:1; }","duration":"126.096897ms","start":"2026-10-09T11:35:06.241274Z","end":"2026-10-09T11:35:06.367371Z","steps":["trace[623372273] 'process raft request' (duration: 124.518667ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:35:06.599532Z","caller":"traceutil/trace.go:171","msg":"trace[1416582296] linearizableReadLoop","detail":"{readStateIndex:19313; appliedIndex:19310; }","duration":"203.099073ms","start":"2026-10-09T11:35:06.396407Z","end":"2026-10-09T11:35:06.599506Z","steps":["trace[1416582296] 'read index received' (duration: 166.574492ms)","trace[1416582296] 'applied index is now lower than readState.Index' (duration: 36.523151ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:35:06.599667Z","caller":"traceutil/trace.go:171","msg":"trace[1536079345] transaction","detail":"{read_only:false; response_revision:18674; number_of_response:1; }","duration":"318.303413ms","start":"2026-10-09T11:35:06.281351Z","end":"2026-10-09T11:35:06.599655Z","steps":["trace[1536079345] 'process raft request' (duration: 281.69301ms)","trace[1536079345] 'compare' (duration: 36.186985ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:35:06.599994Z","caller":"traceutil/trace.go:171","msg":"trace[121979322] transaction","detail":"{read_only:false; response_revision:18675; number_of_response:1; }","duration":"278.560001ms","start":"2026-10-09T11:35:06.321406Z","end":"2026-10-09T11:35:06.599966Z","steps":["trace[121979322] 'process raft request' (duration: 278.00586ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:35:06.600232Z","caller":"traceutil/trace.go:171","msg":"trace[807494795] transaction","detail":"{read_only:false; response_revision:18676; number_of_response:1; }","duration":"257.697097ms","start":"2026-10-09T11:35:06.342516Z","end":"2026-10-09T11:35:06.600213Z","steps":["trace[807494795] 'process raft request' (duration: 256.941442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:35:06.600413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.98864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-nbwqv\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-10-09T11:35:06.600466Z","caller":"traceutil/trace.go:171","msg":"trace[875033837] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-nbwqv; range_end:; response_count:1; response_revision:18676; }","duration":"204.095812ms","start":"2026-10-09T11:35:06.396357Z","end":"2026-10-09T11:35:06.600453Z","steps":["trace[875033837] 'agreement among raft nodes before linearized reading' (duration: 203.349998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:35:06.600683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:35:06.281331Z","time spent":"318.722761ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:35:06.600652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.045758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-10-09T11:35:06.600766Z","caller":"traceutil/trace.go:171","msg":"trace[126536455] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:18676; }","duration":"178.831964ms","start":"2026-10-09T11:35:06.421909Z","end":"2026-10-09T11:35:06.600741Z","steps":["trace[126536455] 'agreement among raft nodes before linearized reading' (duration: 177.897836ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:35:22.26405Z","caller":"traceutil/trace.go:171","msg":"trace[759529816] transaction","detail":"{read_only:false; response_revision:18913; number_of_response:1; }","duration":"117.378131ms","start":"2026-10-09T11:35:22.14663Z","end":"2026-10-09T11:35:22.264008Z","steps":["trace[759529816] 'process raft request' (duration: 116.80413ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:36:00.859301Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T11:36:00.96023Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T11:36:00.960352Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T11:36:51.101786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17225} {"level":"info","ts":"2026-10-09T11:36:51.209948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17225,"took":"104.952476ms","hash":4038177743,"current-db-size-bytes":57548800,"current-db-size":"58 MB","current-db-size-in-use-bytes":34148352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T11:36:51.210276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4038177743,"revision":17225,"compact-revision":14664} {"level":"info","ts":"2026-10-09T11:41:23.263651Z","caller":"traceutil/trace.go:171","msg":"trace[244870771] transaction","detail":"{read_only:false; response_revision:21539; number_of_response:1; }","duration":"217.283891ms","start":"2026-10-09T11:41:23.046339Z","end":"2026-10-09T11:41:23.263623Z","steps":["trace[244870771] 'process raft request' (duration: 217.104417ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:41:23.753858Z","caller":"traceutil/trace.go:171","msg":"trace[810140392] transaction","detail":"{read_only:false; response_revision:21540; number_of_response:1; }","duration":"123.011978ms","start":"2026-10-09T11:41:23.630806Z","end":"2026-10-09T11:41:23.753818Z","steps":["trace[810140392] 'process raft request' (duration: 122.715092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:41:23.941456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.374306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:41:23.941703Z","caller":"traceutil/trace.go:171","msg":"trace[81174680] transaction","detail":"{read_only:false; response_revision:21542; number_of_response:1; }","duration":"139.133393ms","start":"2026-10-09T11:41:23.802546Z","end":"2026-10-09T11:41:23.94168Z","steps":["trace[81174680] 'process raft request' (duration: 139.038951ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:41:23.941789Z","caller":"traceutil/trace.go:171","msg":"trace[517725978] transaction","detail":"{read_only:false; response_revision:21541; number_of_response:1; }","duration":"183.134246ms","start":"2026-10-09T11:41:23.758607Z","end":"2026-10-09T11:41:23.941741Z","steps":["trace[517725978] 'process raft request' (duration: 42.345631ms)","trace[517725978] 'compare' (duration: 140.217943ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:41:24.295929Z","caller":"traceutil/trace.go:171","msg":"trace[1138337763] linearizableReadLoop","detail":"{readStateIndex:22274; appliedIndex:22273; }","duration":"187.560269ms","start":"2026-10-09T11:41:24.108317Z","end":"2026-10-09T11:41:24.295877Z","steps":["trace[1138337763] 'read index received' (duration: 138.494851ms)","trace[1138337763] 'applied index is now lower than readState.Index' (duration: 49.064018ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:41:24.296085Z","caller":"traceutil/trace.go:171","msg":"trace[616709959] transaction","detail":"{read_only:false; response_revision:21543; number_of_response:1; }","duration":"190.552336ms","start":"2026-10-09T11:41:24.105519Z","end":"2026-10-09T11:41:24.296072Z","steps":["trace[616709959] 'process raft request' (duration: 141.356386ms)","trace[616709959] 'compare' (duration: 48.590728ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:41:24.296311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.974337ms","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-10-09T11:41:24.296415Z","caller":"traceutil/trace.go:171","msg":"trace[417196567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21543; }","duration":"188.12988ms","start":"2026-10-09T11:41:24.108262Z","end":"2026-10-09T11:41:24.296392Z","steps":["trace[417196567] 'agreement among raft nodes before linearized reading' (duration: 187.867625ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:41:24.633659Z","caller":"traceutil/trace.go:171","msg":"trace[831539493] transaction","detail":"{read_only:false; response_revision:21544; number_of_response:1; }","duration":"510.632983ms","start":"2026-10-09T11:41:24.122988Z","end":"2026-10-09T11:41:24.633621Z","steps":["trace[831539493] 'process raft request' (duration: 439.455546ms)","trace[831539493] 'compare' (duration: 70.715998ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:41:24.633776Z","caller":"traceutil/trace.go:171","msg":"trace[1436418072] transaction","detail":"{read_only:false; response_revision:21545; number_of_response:1; }","duration":"505.827803ms","start":"2026-10-09T11:41:24.127899Z","end":"2026-10-09T11:41:24.633727Z","steps":["trace[1436418072] 'process raft request' (duration: 505.640399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:41:24.63384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:41:24.122971Z","time spent":"510.769405ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:41:24.63392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.923699ms","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-10-09T11:41:24.634012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:41:24.127883Z","time spent":"505.992526ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:41:24.634027Z","caller":"traceutil/trace.go:171","msg":"trace[330818376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21545; }","duration":"455.05162ms","start":"2026-10-09T11:41:24.178944Z","end":"2026-10-09T11:41:24.633996Z","steps":["trace[330818376] 'agreement among raft nodes before linearized reading' (duration: 454.790776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:41:24.63412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:41:24.178935Z","time spent":"455.140093ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T11:41:24.634176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.78778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-09T11:41:24.634346Z","caller":"traceutil/trace.go:171","msg":"trace[130098892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21545; }","duration":"525.015464ms","start":"2026-10-09T11:41:24.109307Z","end":"2026-10-09T11:41:24.634322Z","steps":["trace[130098892] 'agreement among raft nodes before linearized reading' (duration: 524.530706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:41:24.634416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:41:24.109289Z","time spent":"525.111496ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T11:41:24.63368Z","caller":"traceutil/trace.go:171","msg":"trace[288873483] linearizableReadLoop","detail":"{readStateIndex:22276; appliedIndex:22274; }","duration":"337.63658ms","start":"2026-10-09T11:41:24.296022Z","end":"2026-10-09T11:41:24.633659Z","steps":["trace[288873483] 'read index received' (duration: 266.402191ms)","trace[288873483] 'applied index is now lower than readState.Index' (duration: 71.232789ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:41:24.638936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.602309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-10-09T11:41:24.638957Z","caller":"traceutil/trace.go:171","msg":"trace[1384486631] transaction","detail":"{read_only:false; response_revision:21546; number_of_response:1; }","duration":"338.053078ms","start":"2026-10-09T11:41:24.300871Z","end":"2026-10-09T11:41:24.638924Z","steps":["trace[1384486631] 'process raft request' (duration: 337.848874ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:41:24.638998Z","caller":"traceutil/trace.go:171","msg":"trace[1280085522] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:21546; }","duration":"281.689611ms","start":"2026-10-09T11:41:24.35729Z","end":"2026-10-09T11:41:24.63898Z","steps":["trace[1280085522] 'agreement among raft nodes before linearized reading' (duration: 281.528138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:41:24.639136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:41:24.300853Z","time spent":"338.16766ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:41:24.930535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.869953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:41:24.930684Z","caller":"traceutil/trace.go:171","msg":"trace[1301946399] linearizableReadLoop","detail":"{readStateIndex:22281; appliedIndex:22280; }","duration":"241.124553ms","start":"2026-10-09T11:41:24.689541Z","end":"2026-10-09T11:41:24.930665Z","steps":["trace[1301946399] 'read index received' (duration: 106.027786ms)","trace[1301946399] 'applied index is now lower than readState.Index' (duration: 135.094967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:41:24.930838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.305255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:41:24.930871Z","caller":"traceutil/trace.go:171","msg":"trace[856482720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21549; }","duration":"241.339746ms","start":"2026-10-09T11:41:24.689522Z","end":"2026-10-09T11:41:24.930862Z","steps":["trace[856482720] 'agreement among raft nodes before linearized reading' (duration: 241.247944ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:41:24.93103Z","caller":"traceutil/trace.go:171","msg":"trace[508693439] transaction","detail":"{read_only:false; response_revision:21549; number_of_response:1; }","duration":"270.255204ms","start":"2026-10-09T11:41:24.660661Z","end":"2026-10-09T11:41:24.930916Z","steps":["trace[508693439] 'process raft request' (duration: 134.903053ms)","trace[508693439] 'compare' (duration: 134.70821ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:41:51.108698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19719} {"level":"info","ts":"2026-10-09T11:41:51.223636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19719,"took":"111.591463ms","hash":2276757832,"current-db-size-bytes":57548800,"current-db-size":"58 MB","current-db-size-in-use-bytes":29339648,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T11:41:51.223702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2276757832,"revision":19719,"compact-revision":17225} {"level":"info","ts":"2026-10-09T11:45:40.853922Z","caller":"traceutil/trace.go:171","msg":"trace[1573959624] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"123.79252ms","start":"2026-10-09T11:45:40.730093Z","end":"2026-10-09T11:45:40.853885Z","steps":["trace[1573959624] 'process raft request' (duration: 123.487554ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:45:41.192492Z","caller":"traceutil/trace.go:171","msg":"trace[1828639406] transaction","detail":"{read_only:false; response_revision:24129; number_of_response:1; }","duration":"244.881167ms","start":"2026-10-09T11:45:40.947585Z","end":"2026-10-09T11:45:41.192466Z","steps":["trace[1828639406] 'process raft request' (duration: 244.724194ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:45:41.195076Z","caller":"traceutil/trace.go:171","msg":"trace[233598743] transaction","detail":"{read_only:false; response_revision:24130; number_of_response:1; }","duration":"209.668672ms","start":"2026-10-09T11:45:40.985359Z","end":"2026-10-09T11:45:41.195027Z","steps":["trace[233598743] 'process raft request' (duration: 209.59667ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:46:51.11618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21682} {"level":"info","ts":"2026-10-09T11:46:51.226897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21682,"took":"106.808828ms","hash":3631414945,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":43425792,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-09T11:46:51.227063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3631414945,"revision":21682,"compact-revision":19719} {"level":"info","ts":"2026-10-09T11:49:00.130531Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006921.wal"} {"level":"info","ts":"2026-10-09T11:49:11.823265Z","caller":"traceutil/trace.go:171","msg":"trace[97824261] linearizableReadLoop","detail":"{readStateIndex:27070; appliedIndex:27069; }","duration":"131.786851ms","start":"2026-10-09T11:49:11.691443Z","end":"2026-10-09T11:49:11.823229Z","steps":["trace[97824261] 'read index received' (duration: 131.563046ms)","trace[97824261] 'applied index is now lower than readState.Index' (duration: 221.524µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:11.823416Z","caller":"traceutil/trace.go:171","msg":"trace[1545646923] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"143.4384ms","start":"2026-10-09T11:49:11.679923Z","end":"2026-10-09T11:49:11.823361Z","steps":["trace[1545646923] 'process raft request' (duration: 143.045413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:11.823566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.091725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:11.823635Z","caller":"traceutil/trace.go:171","msg":"trace[343185674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26206; }","duration":"132.218798ms","start":"2026-10-09T11:49:11.6914Z","end":"2026-10-09T11:49:11.823619Z","steps":["trace[343185674] 'agreement among raft nodes before linearized reading' (duration: 132.077855ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:12.139268Z","caller":"traceutil/trace.go:171","msg":"trace[305708650] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"162.588092ms","start":"2026-10-09T11:49:11.976653Z","end":"2026-10-09T11:49:12.139241Z","steps":["trace[305708650] 'process raft request' (duration: 162.263846ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:12.147582Z","caller":"traceutil/trace.go:171","msg":"trace[84066996] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"158.883233ms","start":"2026-10-09T11:49:11.988684Z","end":"2026-10-09T11:49:12.147567Z","steps":["trace[84066996] 'process raft request' (duration: 158.746501ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:12.147707Z","caller":"traceutil/trace.go:171","msg":"trace[614882070] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"127.426709ms","start":"2026-10-09T11:49:12.020271Z","end":"2026-10-09T11:49:12.147698Z","steps":["trace[614882070] 'process raft request' (duration: 127.242575ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:16.569056Z","caller":"traceutil/trace.go:171","msg":"trace[2042590976] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"165.522388ms","start":"2026-10-09T11:49:16.403492Z","end":"2026-10-09T11:49:16.569014Z","steps":["trace[2042590976] 'process raft request' (duration: 165.308234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:16.827301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.420345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:49:16.827567Z","caller":"traceutil/trace.go:171","msg":"trace[352072494] linearizableReadLoop","detail":"{readStateIndex:27107; appliedIndex:27105; }","duration":"414.720926ms","start":"2026-10-09T11:49:16.412829Z","end":"2026-10-09T11:49:16.82755Z","steps":["trace[352072494] 'read index received' (duration: 156.383884ms)","trace[352072494] 'applied index is now lower than readState.Index' (duration: 258.336012ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:16.827554Z","caller":"traceutil/trace.go:171","msg":"trace[632152281] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"422.587696ms","start":"2026-10-09T11:49:16.404924Z","end":"2026-10-09T11:49:16.827512Z","steps":["trace[632152281] 'process raft request' (duration: 274.845964ms)","trace[632152281] 'compare' (duration: 146.02537ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:16.827649Z","caller":"traceutil/trace.go:171","msg":"trace[1958319951] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"418.973757ms","start":"2026-10-09T11:49:16.408665Z","end":"2026-10-09T11:49:16.827639Z","steps":["trace[1958319951] 'process raft request' (duration: 418.746133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:16.82771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:16.408643Z","time spent":"419.035088ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:49:16.827736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:16.404897Z","time spent":"422.725478ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:16.827818Z","caller":"traceutil/trace.go:171","msg":"trace[463979346] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"413.233718ms","start":"2026-10-09T11:49:16.414563Z","end":"2026-10-09T11:49:16.827797Z","steps":["trace[463979346] 'process raft request' (duration: 412.944982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:16.827978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:16.414533Z","time spent":"413.351881ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:49:16.827991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.181766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-10-09T11:49:16.828049Z","caller":"traceutil/trace.go:171","msg":"trace[560384378] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:26243; }","duration":"415.302838ms","start":"2026-10-09T11:49:16.412729Z","end":"2026-10-09T11:49:16.828031Z","steps":["trace[560384378] 'agreement among raft nodes before linearized reading' (duration: 415.136234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:16.828094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:16.412693Z","time spent":"415.39355ms","remote":"127.0.0.1:45890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-10-09T11:49:16.841369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.501203ms","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-10-09T11:49:16.841431Z","caller":"traceutil/trace.go:171","msg":"trace[1828122517] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26244; }","duration":"387.614145ms","start":"2026-10-09T11:49:16.453802Z","end":"2026-10-09T11:49:16.841417Z","steps":["trace[1828122517] 'agreement among raft nodes before linearized reading' (duration: 387.528974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:16.841466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:16.453769Z","time spent":"387.689595ms","remote":"127.0.0.1:57186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-09T11:49:16.84166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.863641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:16.841739Z","caller":"traceutil/trace.go:171","msg":"trace[877623211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26244; }","duration":"150.339231ms","start":"2026-10-09T11:49:16.69138Z","end":"2026-10-09T11:49:16.84172Z","steps":["trace[877623211] 'agreement among raft nodes before linearized reading' (duration: 149.978404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:16.841716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.972787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:16.84182Z","caller":"traceutil/trace.go:171","msg":"trace[1463880165] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26244; }","duration":"256.244382ms","start":"2026-10-09T11:49:16.585541Z","end":"2026-10-09T11:49:16.841786Z","steps":["trace[1463880165] 'agreement among raft nodes before linearized reading' (duration: 255.987737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:17.121882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.91504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-10-09T11:49:17.121976Z","caller":"traceutil/trace.go:171","msg":"trace[2114118363] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26244; }","duration":"107.087344ms","start":"2026-10-09T11:49:17.014869Z","end":"2026-10-09T11:49:17.121957Z","steps":["trace[2114118363] 'range keys from in-memory index tree' (duration: 106.697956ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:17.443307Z","caller":"traceutil/trace.go:171","msg":"trace[338389644] linearizableReadLoop","detail":"{readStateIndex:27111; appliedIndex:27109; }","duration":"222.580645ms","start":"2026-10-09T11:49:17.2207Z","end":"2026-10-09T11:49:17.443281Z","steps":["trace[338389644] 'read index received' (duration: 57.269451ms)","trace[338389644] 'applied index is now lower than readState.Index' (duration: 165.309984ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:17.443434Z","caller":"traceutil/trace.go:171","msg":"trace[779670535] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"239.599087ms","start":"2026-10-09T11:49:17.203756Z","end":"2026-10-09T11:49:17.443355Z","steps":["trace[779670535] 'process raft request' (duration: 239.357293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:17.443572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.844571ms","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-10-09T11:49:17.443657Z","caller":"traceutil/trace.go:171","msg":"trace[984878715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26246; }","duration":"222.970993ms","start":"2026-10-09T11:49:17.22066Z","end":"2026-10-09T11:49:17.443631Z","steps":["trace[984878715] 'agreement among raft nodes before linearized reading' (duration: 222.729028ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:17.563901Z","caller":"traceutil/trace.go:171","msg":"trace[63301424] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"183.253044ms","start":"2026-10-09T11:49:17.380607Z","end":"2026-10-09T11:49:17.56386Z","steps":["trace[63301424] 'process raft request' (duration: 174.357355ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:17.563993Z","caller":"traceutil/trace.go:171","msg":"trace[543030631] linearizableReadLoop","detail":"{readStateIndex:27112; appliedIndex:27111; }","duration":"120.501397ms","start":"2026-10-09T11:49:17.443448Z","end":"2026-10-09T11:49:17.56395Z","steps":["trace[543030631] 'read index received' (duration: 111.537868ms)","trace[543030631] 'applied index is now lower than readState.Index' (duration: 8.960059ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:17.564536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.749859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:17.564908Z","caller":"traceutil/trace.go:171","msg":"trace[339429037] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"116.132845ms","start":"2026-10-09T11:49:17.448724Z","end":"2026-10-09T11:49:17.564857Z","steps":["trace[339429037] 'process raft request' (duration: 115.110345ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:17.56468Z","caller":"traceutil/trace.go:171","msg":"trace[313421956] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26247; }","duration":"276.966914ms","start":"2026-10-09T11:49:17.287677Z","end":"2026-10-09T11:49:17.564643Z","steps":["trace[313421956] 'agreement among raft nodes before linearized reading' (duration: 276.677948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:17.874914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.759549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:17.87499Z","caller":"traceutil/trace.go:171","msg":"trace[958125434] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26248; }","duration":"131.853571ms","start":"2026-10-09T11:49:17.743114Z","end":"2026-10-09T11:49:17.874968Z","steps":["trace[958125434] 'count revisions from in-memory index tree' (duration: 131.683998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:18.25072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.944721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:49:18.250839Z","caller":"traceutil/trace.go:171","msg":"trace[75021106] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"313.039986ms","start":"2026-10-09T11:49:17.937775Z","end":"2026-10-09T11:49:18.250815Z","steps":["trace[75021106] 'process raft request' (duration: 169.870591ms)","trace[75021106] 'compare' (duration: 142.721037ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:18.250911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:17.937736Z","time spent":"313.138618ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:18.436429Z","caller":"traceutil/trace.go:171","msg":"trace[206177097] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"268.973093ms","start":"2026-10-09T11:49:18.167431Z","end":"2026-10-09T11:49:18.436404Z","steps":["trace[206177097] 'process raft request' (duration: 268.80845ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:18.436597Z","caller":"traceutil/trace.go:171","msg":"trace[284107796] linearizableReadLoop","detail":"{readStateIndex:27119; appliedIndex:27117; }","duration":"177.796229ms","start":"2026-10-09T11:49:18.258792Z","end":"2026-10-09T11:49:18.436588Z","steps":["trace[284107796] 'read index received' (duration: 177.386101ms)","trace[284107796] 'applied index is now lower than readState.Index' (duration: 409.338µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:18.436765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.952352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19231"} {"level":"info","ts":"2026-10-09T11:49:18.436801Z","caller":"traceutil/trace.go:171","msg":"trace[1748513551] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26254; }","duration":"178.046224ms","start":"2026-10-09T11:49:18.258744Z","end":"2026-10-09T11:49:18.43679Z","steps":["trace[1748513551] 'agreement among raft nodes before linearized reading' (duration: 177.882131ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:18.436881Z","caller":"traceutil/trace.go:171","msg":"trace[618720937] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"268.042655ms","start":"2026-10-09T11:49:18.168787Z","end":"2026-10-09T11:49:18.436829Z","steps":["trace[618720937] 'process raft request' (duration: 267.578476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:19.594677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.60859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-09T11:49:19.594761Z","caller":"traceutil/trace.go:171","msg":"trace[354698582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26259; }","duration":"146.789414ms","start":"2026-10-09T11:49:19.447951Z","end":"2026-10-09T11:49:19.59474Z","steps":["trace[354698582] 'range keys from in-memory index tree' (duration: 146.488418ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:19.80771Z","caller":"traceutil/trace.go:171","msg":"trace[312948496] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"205.861309ms","start":"2026-10-09T11:49:19.601824Z","end":"2026-10-09T11:49:19.807686Z","steps":["trace[312948496] 'process raft request' (duration: 205.763418ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:19.807983Z","caller":"traceutil/trace.go:171","msg":"trace[1768180047] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"206.156085ms","start":"2026-10-09T11:49:19.601794Z","end":"2026-10-09T11:49:19.80795Z","steps":["trace[1768180047] 'process raft request' (duration: 182.636481ms)","trace[1768180047] 'compare' (duration: 22.796941ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:20.50443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.705884ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T11:49:20.50488Z","caller":"traceutil/trace.go:171","msg":"trace[948040523] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"278.940421ms","start":"2026-10-09T11:49:20.225912Z","end":"2026-10-09T11:49:20.504853Z","steps":["trace[948040523] 'process raft request' (duration: 278.628336ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:20.508113Z","caller":"traceutil/trace.go:171","msg":"trace[747216780] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"244.008381ms","start":"2026-10-09T11:49:20.264038Z","end":"2026-10-09T11:49:20.508046Z","steps":["trace[747216780] 'process raft request' (duration: 240.79856ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:20.696795Z","caller":"traceutil/trace.go:171","msg":"trace[1409680742] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"254.129042ms","start":"2026-10-09T11:49:20.442638Z","end":"2026-10-09T11:49:20.696767Z","steps":["trace[1409680742] 'process raft request' (duration: 227.854825ms)","trace[1409680742] 'compare' (duration: 26.059583ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:20.697066Z","caller":"traceutil/trace.go:171","msg":"trace[919307320] transaction","detail":"{read_only:false; response_revision:26270; number_of_response:1; }","duration":"200.801674ms","start":"2026-10-09T11:49:20.496208Z","end":"2026-10-09T11:49:20.69701Z","steps":["trace[919307320] 'process raft request' (duration: 200.512629ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:20.697194Z","caller":"traceutil/trace.go:171","msg":"trace[910259779] linearizableReadLoop","detail":"{readStateIndex:27136; appliedIndex:27134; }","duration":"189.067883ms","start":"2026-10-09T11:49:20.508099Z","end":"2026-10-09T11:49:20.697167Z","steps":["trace[910259779] 'read index received' (duration: 162.395229ms)","trace[910259779] 'applied index is now lower than readState.Index' (duration: 26.670484ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:20.697355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.707703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:20.697389Z","caller":"traceutil/trace.go:171","msg":"trace[59054446] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26271; }","duration":"227.807545ms","start":"2026-10-09T11:49:20.46957Z","end":"2026-10-09T11:49:20.697377Z","steps":["trace[59054446] 'agreement among raft nodes before linearized reading' (duration: 227.717104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:20.697674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.407013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f79e71db-489e-4957-9bfe-71d282604c26\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-10-09T11:49:20.697809Z","caller":"traceutil/trace.go:171","msg":"trace[494893281] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f79e71db-489e-4957-9bfe-71d282604c26; range_end:; response_count:1; response_revision:26271; }","duration":"186.546666ms","start":"2026-10-09T11:49:20.511217Z","end":"2026-10-09T11:49:20.697763Z","steps":["trace[494893281] 'agreement among raft nodes before linearized reading' (duration: 186.2522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:20.697958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.247355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T11:49:20.697993Z","caller":"traceutil/trace.go:171","msg":"trace[1999478345] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26271; }","duration":"153.332587ms","start":"2026-10-09T11:49:20.54465Z","end":"2026-10-09T11:49:20.697983Z","steps":["trace[1999478345] 'agreement among raft nodes before linearized reading' (duration: 153.186055ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:21.005048Z","caller":"traceutil/trace.go:171","msg":"trace[1324383899] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"118.300445ms","start":"2026-10-09T11:49:20.886719Z","end":"2026-10-09T11:49:21.00502Z","steps":["trace[1324383899] 'process raft request' (duration: 118.131592ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:21.006707Z","caller":"traceutil/trace.go:171","msg":"trace[1476432149] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"118.514089ms","start":"2026-10-09T11:49:20.888157Z","end":"2026-10-09T11:49:21.006671Z","steps":["trace[1476432149] 'process raft request' (duration: 118.203563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:21.338974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.514304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:49:21.339188Z","caller":"traceutil/trace.go:171","msg":"trace[664913611] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"327.108501ms","start":"2026-10-09T11:49:21.012031Z","end":"2026-10-09T11:49:21.33914Z","steps":["trace[664913611] 'process raft request' (duration: 221.338293ms)","trace[664913611] 'compare' (duration: 104.951214ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:21.339383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:21.012006Z","time spent":"327.272104ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:21.519262Z","caller":"traceutil/trace.go:171","msg":"trace[1900078107] linearizableReadLoop","detail":"{readStateIndex:27141; appliedIndex:27140; }","duration":"164.410827ms","start":"2026-10-09T11:49:21.354815Z","end":"2026-10-09T11:49:21.519226Z","steps":["trace[1900078107] 'read index received' (duration: 130.564847ms)","trace[1900078107] 'applied index is now lower than readState.Index' (duration: 33.84494ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:21.51928Z","caller":"traceutil/trace.go:171","msg":"trace[1923100940] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"173.534649ms","start":"2026-10-09T11:49:21.345709Z","end":"2026-10-09T11:49:21.519244Z","steps":["trace[1923100940] 'process raft request' (duration: 139.792351ms)","trace[1923100940] 'compare' (duration: 33.448012ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:21.519458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.62444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:21.519537Z","caller":"traceutil/trace.go:171","msg":"trace[621929190] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26275; }","duration":"164.718712ms","start":"2026-10-09T11:49:21.354797Z","end":"2026-10-09T11:49:21.519516Z","steps":["trace[621929190] 'agreement among raft nodes before linearized reading' (duration: 164.59026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:21.519487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.847554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:21.519749Z","caller":"traceutil/trace.go:171","msg":"trace[754450523] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26275; }","duration":"148.174349ms","start":"2026-10-09T11:49:21.371562Z","end":"2026-10-09T11:49:21.519737Z","steps":["trace[754450523] 'agreement among raft nodes before linearized reading' (duration: 147.844573ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:21.702295Z","caller":"traceutil/trace.go:171","msg":"trace[81173794] transaction","detail":"{read_only:false; response_revision:26276; number_of_response:1; }","duration":"100.54744ms","start":"2026-10-09T11:49:21.60172Z","end":"2026-10-09T11:49:21.702268Z","steps":["trace[81173794] 'process raft request' (duration: 99.648952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:22.053404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.851455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T11:49:22.053481Z","caller":"traceutil/trace.go:171","msg":"trace[2094289167] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26278; }","duration":"182.994897ms","start":"2026-10-09T11:49:21.870467Z","end":"2026-10-09T11:49:22.053462Z","steps":["trace[2094289167] 'range keys from in-memory index tree' (duration: 182.503628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:22.053578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.324451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T11:49:22.053659Z","caller":"traceutil/trace.go:171","msg":"trace[534222592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26278; }","duration":"241.458392ms","start":"2026-10-09T11:49:21.812179Z","end":"2026-10-09T11:49:22.053637Z","steps":["trace[534222592] 'range keys from in-memory index tree' (duration: 241.111996ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:22.186406Z","caller":"traceutil/trace.go:171","msg":"trace[1503522044] linearizableReadLoop","detail":"{readStateIndex:27145; appliedIndex:27144; }","duration":"224.635505ms","start":"2026-10-09T11:49:21.961746Z","end":"2026-10-09T11:49:22.186381Z","steps":["trace[1503522044] 'read index received' (duration: 224.494173ms)","trace[1503522044] 'applied index is now lower than readState.Index' (duration: 140.122µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:22.1865Z","caller":"traceutil/trace.go:171","msg":"trace[469274412] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"273.470738ms","start":"2026-10-09T11:49:21.912974Z","end":"2026-10-09T11:49:22.186445Z","steps":["trace[469274412] 'process raft request' (duration: 273.215104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:22.186652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.86411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T11:49:22.186694Z","caller":"traceutil/trace.go:171","msg":"trace[178436950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26279; }","duration":"224.944601ms","start":"2026-10-09T11:49:21.961739Z","end":"2026-10-09T11:49:22.186684Z","steps":["trace[178436950] 'agreement among raft nodes before linearized reading' (duration: 224.772298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:22.206597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.6794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T11:49:22.206675Z","caller":"traceutil/trace.go:171","msg":"trace[1583108167] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26280; }","duration":"148.803872ms","start":"2026-10-09T11:49:22.057859Z","end":"2026-10-09T11:49:22.206663Z","steps":["trace[1583108167] 'agreement among raft nodes before linearized reading' (duration: 148.623618ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:22.206657Z","caller":"traceutil/trace.go:171","msg":"trace[246283013] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"146.913166ms","start":"2026-10-09T11:49:22.059697Z","end":"2026-10-09T11:49:22.20661Z","steps":["trace[246283013] 'process raft request' (duration: 146.706633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:22.437187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.535007ms","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-10-09T11:49:22.437272Z","caller":"traceutil/trace.go:171","msg":"trace[1056903047] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26281; }","duration":"147.67976ms","start":"2026-10-09T11:49:22.289573Z","end":"2026-10-09T11:49:22.437253Z","steps":["trace[1056903047] 'range keys from in-memory index tree' (duration: 147.425686ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:22.859796Z","caller":"traceutil/trace.go:171","msg":"trace[1539019670] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"155.557609ms","start":"2026-10-09T11:49:22.704214Z","end":"2026-10-09T11:49:22.859771Z","steps":["trace[1539019670] 'process raft request' (duration: 155.412616ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:22.91094Z","caller":"traceutil/trace.go:171","msg":"trace[2094240092] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"204.625937ms","start":"2026-10-09T11:49:22.706296Z","end":"2026-10-09T11:49:22.910922Z","steps":["trace[2094240092] 'process raft request' (duration: 201.988477ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:23.11373Z","caller":"traceutil/trace.go:171","msg":"trace[2125840999] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"103.391274ms","start":"2026-10-09T11:49:23.010309Z","end":"2026-10-09T11:49:23.113701Z","steps":["trace[2125840999] 'process raft request' (duration: 103.19926ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:23.125016Z","caller":"traceutil/trace.go:171","msg":"trace[254255496] linearizableReadLoop","detail":"{readStateIndex:27153; appliedIndex:27151; }","duration":"103.17997ms","start":"2026-10-09T11:49:23.021806Z","end":"2026-10-09T11:49:23.124986Z","steps":["trace[254255496] 'read index received' (duration: 91.745774ms)","trace[254255496] 'applied index is now lower than readState.Index' (duration: 11.432846ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:23.125054Z","caller":"traceutil/trace.go:171","msg":"trace[63073640] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"113.27547ms","start":"2026-10-09T11:49:23.011765Z","end":"2026-10-09T11:49:23.12504Z","steps":["trace[63073640] 'process raft request' (duration: 113.042785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:23.125197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.358442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T11:49:23.125228Z","caller":"traceutil/trace.go:171","msg":"trace[986621710] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:26287; }","duration":"103.449744ms","start":"2026-10-09T11:49:23.021771Z","end":"2026-10-09T11:49:23.125221Z","steps":["trace[986621710] 'agreement among raft nodes before linearized reading' (duration: 103.331552ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:32.370289Z","caller":"traceutil/trace.go:171","msg":"trace[24517141] transaction","detail":"{read_only:false; response_revision:26349; number_of_response:1; }","duration":"512.7835ms","start":"2026-10-09T11:49:31.857474Z","end":"2026-10-09T11:49:32.370257Z","steps":["trace[24517141] 'process raft request' (duration: 470.99459ms)","trace[24517141] 'compare' (duration: 41.693528ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:32.370474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:31.857446Z","time spent":"512.935822ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:32.377995Z","caller":"traceutil/trace.go:171","msg":"trace[951702159] transaction","detail":"{read_only:false; response_revision:26350; number_of_response:1; }","duration":"314.661645ms","start":"2026-10-09T11:49:32.063283Z","end":"2026-10-09T11:49:32.377945Z","steps":["trace[951702159] 'process raft request' (duration: 314.345389ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:32.378069Z","caller":"traceutil/trace.go:171","msg":"trace[208859906] transaction","detail":"{read_only:false; response_revision:26351; number_of_response:1; }","duration":"146.926756ms","start":"2026-10-09T11:49:32.231117Z","end":"2026-10-09T11:49:32.378044Z","steps":["trace[208859906] 'process raft request' (duration: 146.647431ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:32.378269Z","caller":"traceutil/trace.go:171","msg":"trace[1186335163] linearizableReadLoop","detail":"{readStateIndex:27219; appliedIndex:27216; }","duration":"123.851769ms","start":"2026-10-09T11:49:32.254154Z","end":"2026-10-09T11:49:32.378006Z","steps":["trace[1186335163] 'read index received' (duration: 74.328834ms)","trace[1186335163] 'applied index is now lower than readState.Index' (duration: 49.521915ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:32.378341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.164556ms","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-10-09T11:49:32.378419Z","caller":"traceutil/trace.go:171","msg":"trace[170833728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26351; }","duration":"124.283078ms","start":"2026-10-09T11:49:32.254118Z","end":"2026-10-09T11:49:32.378401Z","steps":["trace[170833728] 'agreement among raft nodes before linearized reading' (duration: 124.034273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:32.378364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:32.063244Z","time spent":"314.959731ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:49:32.378713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.327121ms","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-10-09T11:49:32.378762Z","caller":"traceutil/trace.go:171","msg":"trace[1529614972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26351; }","duration":"122.428804ms","start":"2026-10-09T11:49:32.256319Z","end":"2026-10-09T11:49:32.378748Z","steps":["trace[1529614972] 'agreement among raft nodes before linearized reading' (duration: 122.073507ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:32.661609Z","caller":"traceutil/trace.go:171","msg":"trace[1078446839] transaction","detail":"{read_only:false; response_revision:26352; number_of_response:1; }","duration":"278.8727ms","start":"2026-10-09T11:49:32.382696Z","end":"2026-10-09T11:49:32.661569Z","steps":["trace[1078446839] 'process raft request' (duration: 253.799186ms)","trace[1078446839] 'compare' (duration: 24.934711ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:33.217636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.599022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:49:33.217822Z","caller":"traceutil/trace.go:171","msg":"trace[291672280] linearizableReadLoop","detail":"{readStateIndex:27221; appliedIndex:27219; }","duration":"804.191265ms","start":"2026-10-09T11:49:32.413617Z","end":"2026-10-09T11:49:33.217808Z","steps":["trace[291672280] 'read index received' (duration: 222.904791ms)","trace[291672280] 'applied index is now lower than readState.Index' (duration: 581.285544ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:33.21788Z","caller":"traceutil/trace.go:171","msg":"trace[1070717955] transaction","detail":"{read_only:false; response_revision:26354; number_of_response:1; }","duration":"634.171623ms","start":"2026-10-09T11:49:32.5837Z","end":"2026-10-09T11:49:33.217872Z","steps":["trace[1070717955] 'process raft request' (duration: 634.02204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:33.217924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:32.583676Z","time spent":"634.220934ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:33.218012Z","caller":"traceutil/trace.go:171","msg":"trace[618310511] transaction","detail":"{read_only:false; response_revision:26355; number_of_response:1; }","duration":"616.935057ms","start":"2026-10-09T11:49:32.60107Z","end":"2026-10-09T11:49:33.218005Z","steps":["trace[618310511] 'process raft request' (duration: 616.702622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:33.218045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:32.601057Z","time spent":"616.969228ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:49:33.218197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.579542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-09T11:49:33.218221Z","caller":"traceutil/trace.go:171","msg":"trace[1063263647] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26355; }","duration":"804.644704ms","start":"2026-10-09T11:49:32.413567Z","end":"2026-10-09T11:49:33.218212Z","steps":["trace[1063263647] 'agreement among raft nodes before linearized reading' (duration: 804.546842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:33.218243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:32.413546Z","time spent":"804.689285ms","remote":"127.0.0.1:45890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"info","ts":"2026-10-09T11:49:33.218385Z","caller":"traceutil/trace.go:171","msg":"trace[77508173] transaction","detail":"{read_only:false; response_revision:26353; number_of_response:1; }","duration":"834.109381ms","start":"2026-10-09T11:49:32.384232Z","end":"2026-10-09T11:49:33.218341Z","steps":["trace[77508173] 'process raft request' (duration: 651.711244ms)","trace[77508173] 'compare' (duration: 181.410449ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:33.218588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:32.384211Z","time spent":"834.249654ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:33.221653Z","caller":"traceutil/trace.go:171","msg":"trace[409356485] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"277.171917ms","start":"2026-10-09T11:49:32.94446Z","end":"2026-10-09T11:49:33.221632Z","steps":["trace[409356485] 'process raft request' (duration: 277.128467ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:33.222001Z","caller":"traceutil/trace.go:171","msg":"trace[534631093] transaction","detail":"{read_only:false; response_revision:26356; number_of_response:1; }","duration":"318.936186ms","start":"2026-10-09T11:49:32.903054Z","end":"2026-10-09T11:49:33.22199Z","steps":["trace[534631093] 'process raft request' (duration: 318.426807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:33.222111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:32.903037Z","time spent":"318.986727ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:49:33.222691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.739135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T11:49:33.222724Z","caller":"traceutil/trace.go:171","msg":"trace[2081474292] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26359; }","duration":"652.808065ms","start":"2026-10-09T11:49:32.569906Z","end":"2026-10-09T11:49:33.222714Z","steps":["trace[2081474292] 'agreement among raft nodes before linearized reading' (duration: 652.672803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:33.222748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:32.569886Z","time spent":"652.856437ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-09T11:49:33.727508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.376332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T11:49:33.72758Z","caller":"traceutil/trace.go:171","msg":"trace[2114236696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26359; }","duration":"122.503774ms","start":"2026-10-09T11:49:33.605059Z","end":"2026-10-09T11:49:33.727563Z","steps":["trace[2114236696] 'range keys from in-memory index tree' (duration: 122.220069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:33.727598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.779425ms","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-10-09T11:49:33.727664Z","caller":"traceutil/trace.go:171","msg":"trace[1780355697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26359; }","duration":"335.884057ms","start":"2026-10-09T11:49:33.391761Z","end":"2026-10-09T11:49:33.727645Z","steps":["trace[1780355697] 'range keys from in-memory index tree' (duration: 335.631502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:33.727699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:33.391746Z","time spent":"335.944678ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T11:49:33.727788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.373862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:33.727822Z","caller":"traceutil/trace.go:171","msg":"trace[609324799] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26359; }","duration":"114.423922ms","start":"2026-10-09T11:49:33.613389Z","end":"2026-10-09T11:49:33.727813Z","steps":["trace[609324799] 'count revisions from in-memory index tree' (duration: 114.33126ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:37.391429Z","caller":"traceutil/trace.go:171","msg":"trace[1525274346] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"161.866009ms","start":"2026-10-09T11:49:37.229537Z","end":"2026-10-09T11:49:37.391403Z","steps":["trace[1525274346] 'process raft request' (duration: 161.704086ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:37.424678Z","caller":"traceutil/trace.go:171","msg":"trace[1193376358] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"192.364655ms","start":"2026-10-09T11:49:37.232293Z","end":"2026-10-09T11:49:37.424658Z","steps":["trace[1193376358] 'process raft request' (duration: 192.212492ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:37.424994Z","caller":"traceutil/trace.go:171","msg":"trace[67389420] transaction","detail":"{read_only:false; response_revision:26443; number_of_response:1; }","duration":"190.307776ms","start":"2026-10-09T11:49:37.234659Z","end":"2026-10-09T11:49:37.424967Z","steps":["trace[67389420] 'process raft request' (duration: 189.955519ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:37.425231Z","caller":"traceutil/trace.go:171","msg":"trace[74611532] transaction","detail":"{read_only:false; response_revision:26444; number_of_response:1; }","duration":"187.396311ms","start":"2026-10-09T11:49:37.237821Z","end":"2026-10-09T11:49:37.425217Z","steps":["trace[74611532] 'process raft request' (duration: 186.997734ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:37.425328Z","caller":"traceutil/trace.go:171","msg":"trace[2138070305] transaction","detail":"{read_only:false; response_revision:26445; number_of_response:1; }","duration":"186.552425ms","start":"2026-10-09T11:49:37.238764Z","end":"2026-10-09T11:49:37.425317Z","steps":["trace[2138070305] 'process raft request' (duration: 186.25711ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:37.425402Z","caller":"traceutil/trace.go:171","msg":"trace[2096026432] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"185.636827ms","start":"2026-10-09T11:49:37.239757Z","end":"2026-10-09T11:49:37.425394Z","steps":["trace[2096026432] 'process raft request' (duration: 185.424533ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:37.425353Z","caller":"traceutil/trace.go:171","msg":"trace[1076437656] linearizableReadLoop","detail":"{readStateIndex:27313; appliedIndex:27309; }","duration":"187.34408ms","start":"2026-10-09T11:49:37.237983Z","end":"2026-10-09T11:49:37.425327Z","steps":["trace[1076437656] 'read index received' (duration: 153.381179ms)","trace[1076437656] 'applied index is now lower than readState.Index' (duration: 33.961351ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:37.425711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.715387ms","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-10-09T11:49:37.425773Z","caller":"traceutil/trace.go:171","msg":"trace[555974649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26446; }","duration":"187.829849ms","start":"2026-10-09T11:49:37.237924Z","end":"2026-10-09T11:49:37.425754Z","steps":["trace[555974649] 'agreement among raft nodes before linearized reading' (duration: 187.653456ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:38.821532Z","caller":"traceutil/trace.go:171","msg":"trace[1358440223] linearizableReadLoop","detail":"{readStateIndex:27323; appliedIndex:27322; }","duration":"130.263222ms","start":"2026-10-09T11:49:38.69124Z","end":"2026-10-09T11:49:38.821504Z","steps":["trace[1358440223] 'read index received' (duration: 130.022807ms)","trace[1358440223] 'applied index is now lower than readState.Index' (duration: 239.265µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:38.821598Z","caller":"traceutil/trace.go:171","msg":"trace[1778531570] transaction","detail":"{read_only:false; response_revision:26454; number_of_response:1; }","duration":"130.68718ms","start":"2026-10-09T11:49:38.690869Z","end":"2026-10-09T11:49:38.821556Z","steps":["trace[1778531570] 'process raft request' (duration: 130.476366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:38.821701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.444275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:49:38.821798Z","caller":"traceutil/trace.go:171","msg":"trace[1223077207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26454; }","duration":"130.570268ms","start":"2026-10-09T11:49:38.691214Z","end":"2026-10-09T11:49:38.821785Z","steps":["trace[1223077207] 'agreement among raft nodes before linearized reading' (duration: 130.433656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:43.766874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.422375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:49:43.767605Z","caller":"traceutil/trace.go:171","msg":"trace[370432275] linearizableReadLoop","detail":"{readStateIndex:27382; appliedIndex:27377; }","duration":"292.739511ms","start":"2026-10-09T11:49:43.474849Z","end":"2026-10-09T11:49:43.767589Z","steps":["trace[370432275] 'read index received' (duration: 189.283496ms)","trace[370432275] 'applied index is now lower than readState.Index' (duration: 103.455135ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:43.76777Z","caller":"traceutil/trace.go:171","msg":"trace[852154311] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"323.398792ms","start":"2026-10-09T11:49:43.444357Z","end":"2026-10-09T11:49:43.767756Z","steps":["trace[852154311] 'process raft request' (duration: 219.833714ms)","trace[852154311] 'compare' (duration: 102.11035ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:43.767854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:43.444333Z","time spent":"323.462393ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:43.768159Z","caller":"traceutil/trace.go:171","msg":"trace[1903783553] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"318.284916ms","start":"2026-10-09T11:49:43.449838Z","end":"2026-10-09T11:49:43.768123Z","steps":["trace[1903783553] 'process raft request' (duration: 317.635243ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:43.768215Z","caller":"traceutil/trace.go:171","msg":"trace[19422671] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"317.940219ms","start":"2026-10-09T11:49:43.450244Z","end":"2026-10-09T11:49:43.768185Z","steps":["trace[19422671] 'process raft request' (duration: 317.266626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:43.768299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.444974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-09T11:49:43.768318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:43.449818Z","time spent":"318.415048ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:43.768369Z","caller":"traceutil/trace.go:171","msg":"trace[1758904016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26511; }","duration":"312.554397ms","start":"2026-10-09T11:49:43.455796Z","end":"2026-10-09T11:49:43.76835Z","steps":["trace[1758904016] 'agreement among raft nodes before linearized reading' (duration: 312.340573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:43.768411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:43.455777Z","time spent":"312.622468ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T11:49:43.768417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:43.450227Z","time spent":"318.116072ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:49:43.768489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.204984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T11:49:43.768486Z","caller":"traceutil/trace.go:171","msg":"trace[1664122865] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"322.907182ms","start":"2026-10-09T11:49:43.445533Z","end":"2026-10-09T11:49:43.76844Z","steps":["trace[1664122865] 'process raft request' (duration: 321.750069ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:43.768526Z","caller":"traceutil/trace.go:171","msg":"trace[1299770537] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26511; }","duration":"290.266065ms","start":"2026-10-09T11:49:43.478249Z","end":"2026-10-09T11:49:43.768516Z","steps":["trace[1299770537] 'agreement among raft nodes before linearized reading' (duration: 290.127422ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:43.768586Z","caller":"traceutil/trace.go:171","msg":"trace[13590338] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"318.752074ms","start":"2026-10-09T11:49:43.449822Z","end":"2026-10-09T11:49:43.768574Z","steps":["trace[13590338] 'process raft request' (duration: 317.592482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:43.768654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:43.445518Z","time spent":"323.046535ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:49:43.768668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:49:43.449803Z","time spent":"318.807515ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:49:43.969952Z","caller":"traceutil/trace.go:171","msg":"trace[1979018781] linearizableReadLoop","detail":"{readStateIndex:27383; appliedIndex:27382; }","duration":"165.234023ms","start":"2026-10-09T11:49:43.804688Z","end":"2026-10-09T11:49:43.969922Z","steps":["trace[1979018781] 'read index received' (duration: 99.576682ms)","trace[1979018781] 'applied index is now lower than readState.Index' (duration: 65.656121ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:43.970334Z","caller":"traceutil/trace.go:171","msg":"trace[375773191] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"196.83396ms","start":"2026-10-09T11:49:43.773484Z","end":"2026-10-09T11:49:43.970318Z","steps":["trace[375773191] 'process raft request' (duration: 130.76172ms)","trace[375773191] 'compare' (duration: 65.232483ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:49:43.970497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.777372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-09T11:49:43.970581Z","caller":"traceutil/trace.go:171","msg":"trace[512939365] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26512; }","duration":"165.880314ms","start":"2026-10-09T11:49:43.804682Z","end":"2026-10-09T11:49:43.970563Z","steps":["trace[512939365] 'agreement among raft nodes before linearized reading' (duration: 165.536329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:43.973034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.555808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-09T11:49:43.973091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.429228ms","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-10-09T11:49:43.973121Z","caller":"traceutil/trace.go:171","msg":"trace[1692290981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26513; }","duration":"102.657061ms","start":"2026-10-09T11:49:43.870428Z","end":"2026-10-09T11:49:43.973085Z","steps":["trace[1692290981] 'agreement among raft nodes before linearized reading' (duration: 102.482567ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:43.973147Z","caller":"traceutil/trace.go:171","msg":"trace[1801843455] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26513; }","duration":"109.49918ms","start":"2026-10-09T11:49:43.863635Z","end":"2026-10-09T11:49:43.973134Z","steps":["trace[1801843455] 'agreement among raft nodes before linearized reading' (duration: 109.433848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:43.973038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.325785ms","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-10-09T11:49:43.973231Z","caller":"traceutil/trace.go:171","msg":"trace[1969556230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26513; }","duration":"110.55802ms","start":"2026-10-09T11:49:43.862648Z","end":"2026-10-09T11:49:43.973206Z","steps":["trace[1969556230] 'agreement among raft nodes before linearized reading' (duration: 110.249324ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:43.973213Z","caller":"traceutil/trace.go:171","msg":"trace[377068445] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"124.814539ms","start":"2026-10-09T11:49:43.848354Z","end":"2026-10-09T11:49:43.973168Z","steps":["trace[377068445] 'process raft request' (duration: 124.444532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:49:43.973037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.559753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T11:49:43.973381Z","caller":"traceutil/trace.go:171","msg":"trace[864155859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26513; }","duration":"107.935859ms","start":"2026-10-09T11:49:43.86543Z","end":"2026-10-09T11:49:43.973366Z","steps":["trace[864155859] 'agreement among raft nodes before linearized reading' (duration: 107.502552ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:52.223754Z","caller":"traceutil/trace.go:171","msg":"trace[52828156] transaction","detail":"{read_only:false; response_revision:26585; number_of_response:1; }","duration":"174.724631ms","start":"2026-10-09T11:49:52.049009Z","end":"2026-10-09T11:49:52.223734Z","steps":["trace[52828156] 'process raft request' (duration: 113.204288ms)","trace[52828156] 'compare' (duration: 61.415521ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:49:52.224477Z","caller":"traceutil/trace.go:171","msg":"trace[1212007809] transaction","detail":"{read_only:false; response_revision:26588; number_of_response:1; }","duration":"172.776734ms","start":"2026-10-09T11:49:52.051689Z","end":"2026-10-09T11:49:52.224466Z","steps":["trace[1212007809] 'process raft request' (duration: 172.749484ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:52.224625Z","caller":"traceutil/trace.go:171","msg":"trace[1391009421] transaction","detail":"{read_only:false; response_revision:26586; number_of_response:1; }","duration":"174.272674ms","start":"2026-10-09T11:49:52.05032Z","end":"2026-10-09T11:49:52.224593Z","steps":["trace[1391009421] 'process raft request' (duration: 174.024899ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:49:52.224672Z","caller":"traceutil/trace.go:171","msg":"trace[1734588246] transaction","detail":"{read_only:false; response_revision:26587; number_of_response:1; }","duration":"174.286214ms","start":"2026-10-09T11:49:52.05036Z","end":"2026-10-09T11:49:52.224646Z","steps":["trace[1734588246] 'process raft request' (duration: 174.039769ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:03.773944Z","caller":"traceutil/trace.go:171","msg":"trace[148564715] transaction","detail":"{read_only:false; response_revision:26694; number_of_response:1; }","duration":"115.174157ms","start":"2026-10-09T11:50:03.658682Z","end":"2026-10-09T11:50:03.773856Z","steps":["trace[148564715] 'process raft request' (duration: 114.938812ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:04.02783Z","caller":"traceutil/trace.go:171","msg":"trace[1905209889] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"105.417962ms","start":"2026-10-09T11:50:03.922394Z","end":"2026-10-09T11:50:04.027812Z","steps":["trace[1905209889] 'process raft request' (duration: 105.361421ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:04.027871Z","caller":"traceutil/trace.go:171","msg":"trace[1645229533] transaction","detail":"{read_only:false; response_revision:26696; number_of_response:1; }","duration":"107.553522ms","start":"2026-10-09T11:50:03.920284Z","end":"2026-10-09T11:50:04.027838Z","steps":["trace[1645229533] 'process raft request' (duration: 107.403799ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:04.028052Z","caller":"traceutil/trace.go:171","msg":"trace[429772381] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"105.559914ms","start":"2026-10-09T11:50:03.922485Z","end":"2026-10-09T11:50:04.028045Z","steps":["trace[429772381] 'process raft request' (duration: 105.298889ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:04.028068Z","caller":"traceutil/trace.go:171","msg":"trace[624183851] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"105.334119ms","start":"2026-10-09T11:50:03.922716Z","end":"2026-10-09T11:50:04.02805Z","steps":["trace[624183851] 'process raft request' (duration: 105.262578ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:04.226833Z","caller":"traceutil/trace.go:171","msg":"trace[257452886] transaction","detail":"{read_only:false; response_revision:26700; number_of_response:1; }","duration":"144.992509ms","start":"2026-10-09T11:50:04.081813Z","end":"2026-10-09T11:50:04.226806Z","steps":["trace[257452886] 'process raft request' (duration: 144.824956ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:04.396946Z","caller":"traceutil/trace.go:171","msg":"trace[354111443] transaction","detail":"{read_only:false; response_revision:26701; number_of_response:1; }","duration":"108.428039ms","start":"2026-10-09T11:50:04.288452Z","end":"2026-10-09T11:50:04.39688Z","steps":["trace[354111443] 'process raft request' (duration: 108.236935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:04.647831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.504789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:04.647942Z","caller":"traceutil/trace.go:171","msg":"trace[485929913] linearizableReadLoop","detail":"{readStateIndex:27578; appliedIndex:27576; }","duration":"357.169078ms","start":"2026-10-09T11:50:04.290754Z","end":"2026-10-09T11:50:04.647924Z","steps":["trace[485929913] 'read index received' (duration: 105.926231ms)","trace[485929913] 'applied index is now lower than readState.Index' (duration: 251.241027ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:04.647971Z","caller":"traceutil/trace.go:171","msg":"trace[1384929707] transaction","detail":"{read_only:false; response_revision:26702; number_of_response:1; }","duration":"358.28344ms","start":"2026-10-09T11:50:04.289652Z","end":"2026-10-09T11:50:04.647935Z","steps":["trace[1384929707] 'process raft request' (duration: 202.495167ms)","trace[1384929707] 'compare' (duration: 155.350456ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:04.648122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:04.289636Z","time spent":"358.396912ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:04.648161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.369842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-09T11:50:04.648245Z","caller":"traceutil/trace.go:171","msg":"trace[1469866043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26702; }","duration":"357.531736ms","start":"2026-10-09T11:50:04.290696Z","end":"2026-10-09T11:50:04.648227Z","steps":["trace[1469866043] 'agreement among raft nodes before linearized reading' (duration: 357.288821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:04.648283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:04.290676Z","time spent":"357.598137ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T11:50:04.656827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.290354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:04.656843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.76021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T11:50:04.656857Z","caller":"traceutil/trace.go:171","msg":"trace[2094020350] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"131.667508ms","start":"2026-10-09T11:50:04.525162Z","end":"2026-10-09T11:50:04.65683Z","steps":["trace[2094020350] 'process raft request' (duration: 131.462434ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:04.65689Z","caller":"traceutil/trace.go:171","msg":"trace[1560987100] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26703; }","duration":"365.825762ms","start":"2026-10-09T11:50:04.291055Z","end":"2026-10-09T11:50:04.656881Z","steps":["trace[1560987100] 'agreement among raft nodes before linearized reading' (duration: 365.676229ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:04.656886Z","caller":"traceutil/trace.go:171","msg":"trace[145523904] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:26703; }","duration":"200.354915ms","start":"2026-10-09T11:50:04.456513Z","end":"2026-10-09T11:50:04.656868Z","steps":["trace[145523904] 'agreement among raft nodes before linearized reading' (duration: 200.254703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:04.656914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:04.291046Z","time spent":"365.863113ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-09T11:50:04.657022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.924414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-09T11:50:04.657028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.000601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T11:50:04.657071Z","caller":"traceutil/trace.go:171","msg":"trace[1520317314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26703; }","duration":"365.980725ms","start":"2026-10-09T11:50:04.291064Z","end":"2026-10-09T11:50:04.657045Z","steps":["trace[1520317314] 'agreement among raft nodes before linearized reading' (duration: 365.880184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:04.657093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:04.291053Z","time spent":"366.035386ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T11:50:04.657101Z","caller":"traceutil/trace.go:171","msg":"trace[1865909674] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26703; }","duration":"325.121543ms","start":"2026-10-09T11:50:04.331961Z","end":"2026-10-09T11:50:04.657082Z","steps":["trace[1865909674] 'agreement among raft nodes before linearized reading' (duration: 324.96006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:04.657171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:04.331942Z","time spent":"325.218126ms","remote":"127.0.0.1:57192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":22,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:04.978273Z","caller":"traceutil/trace.go:171","msg":"trace[1922730719] linearizableReadLoop","detail":"{readStateIndex:27584; appliedIndex:27583; }","duration":"151.962021ms","start":"2026-10-09T11:50:04.826288Z","end":"2026-10-09T11:50:04.97825Z","steps":["trace[1922730719] 'read index received' (duration: 151.752428ms)","trace[1922730719] 'applied index is now lower than readState.Index' (duration: 208.753µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:04.978325Z","caller":"traceutil/trace.go:171","msg":"trace[29386266] transaction","detail":"{read_only:false; response_revision:26707; number_of_response:1; }","duration":"152.166994ms","start":"2026-10-09T11:50:04.826123Z","end":"2026-10-09T11:50:04.97829Z","steps":["trace[29386266] 'process raft request' (duration: 151.969312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:04.978425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.304616ms","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-10-09T11:50:04.978466Z","caller":"traceutil/trace.go:171","msg":"trace[301079846] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26707; }","duration":"109.397638ms","start":"2026-10-09T11:50:04.869059Z","end":"2026-10-09T11:50:04.978457Z","steps":["trace[301079846] 'agreement among raft nodes before linearized reading' (duration: 109.314606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:04.97854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.223206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T11:50:04.978647Z","caller":"traceutil/trace.go:171","msg":"trace[640032307] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26707; }","duration":"152.349048ms","start":"2026-10-09T11:50:04.826262Z","end":"2026-10-09T11:50:04.978611Z","steps":["trace[640032307] 'agreement among raft nodes before linearized reading' (duration: 152.103093ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:06.145767Z","caller":"traceutil/trace.go:171","msg":"trace[667128336] transaction","detail":"{read_only:false; response_revision:26713; number_of_response:1; }","duration":"111.968146ms","start":"2026-10-09T11:50:06.033775Z","end":"2026-10-09T11:50:06.145743Z","steps":["trace[667128336] 'process raft request' (duration: 111.768313ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:06.172579Z","caller":"traceutil/trace.go:171","msg":"trace[1937347992] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"137.780654ms","start":"2026-10-09T11:50:06.034779Z","end":"2026-10-09T11:50:06.172559Z","steps":["trace[1937347992] 'process raft request' (duration: 137.746463ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:06.172683Z","caller":"traceutil/trace.go:171","msg":"trace[1075557037] transaction","detail":"{read_only:false; response_revision:26714; number_of_response:1; }","duration":"138.421576ms","start":"2026-10-09T11:50:06.034232Z","end":"2026-10-09T11:50:06.172654Z","steps":["trace[1075557037] 'process raft request' (duration: 138.14246ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:06.172718Z","caller":"traceutil/trace.go:171","msg":"trace[1888928938] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"138.13179ms","start":"2026-10-09T11:50:06.034559Z","end":"2026-10-09T11:50:06.172691Z","steps":["trace[1888928938] 'process raft request' (duration: 137.935387ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:08.552515Z","caller":"traceutil/trace.go:171","msg":"trace[752228188] transaction","detail":"{read_only:false; response_revision:26732; number_of_response:1; }","duration":"402.441646ms","start":"2026-10-09T11:50:08.150048Z","end":"2026-10-09T11:50:08.55249Z","steps":["trace[752228188] 'process raft request' (duration: 402.280963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.552665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.150027Z","time spent":"402.556907ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:08.96738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.417857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:08.967758Z","caller":"traceutil/trace.go:171","msg":"trace[645784545] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"300.581129ms","start":"2026-10-09T11:50:08.667158Z","end":"2026-10-09T11:50:08.967739Z","steps":["trace[645784545] 'process raft request' (duration: 300.543468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.967854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.66711Z","time spent":"300.693551ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:08.968046Z","caller":"traceutil/trace.go:171","msg":"trace[1379234461] transaction","detail":"{read_only:false; response_revision:26736; number_of_response:1; }","duration":"738.80804ms","start":"2026-10-09T11:50:08.22921Z","end":"2026-10-09T11:50:08.968018Z","steps":["trace[1379234461] 'process raft request' (duration: 738.398222ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:08.968077Z","caller":"traceutil/trace.go:171","msg":"trace[1438106652] transaction","detail":"{read_only:false; response_revision:26737; number_of_response:1; }","duration":"723.709095ms","start":"2026-10-09T11:50:08.24435Z","end":"2026-10-09T11:50:08.968059Z","steps":["trace[1438106652] 'process raft request' (duration: 723.306037ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:08.968085Z","caller":"traceutil/trace.go:171","msg":"trace[1253980821] transaction","detail":"{read_only:false; response_revision:26733; number_of_response:1; }","duration":"790.333893ms","start":"2026-10-09T11:50:08.177685Z","end":"2026-10-09T11:50:08.968019Z","steps":["trace[1253980821] 'process raft request' (duration: 679.187933ms)","trace[1253980821] 'compare' (duration: 110.216863ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:08.96807Z","caller":"traceutil/trace.go:171","msg":"trace[65485361] transaction","detail":"{read_only:false; response_revision:26735; number_of_response:1; }","duration":"788.02686ms","start":"2026-10-09T11:50:08.180014Z","end":"2026-10-09T11:50:08.968041Z","steps":["trace[65485361] 'process raft request' (duration: 787.551401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.968219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.229189Z","time spent":"738.934853ms","remote":"127.0.0.1:45956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5597,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:08.968235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.244345Z","time spent":"723.811877ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:08.968265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.686898ms","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-10-09T11:50:08.968073Z","caller":"traceutil/trace.go:171","msg":"trace[161113328] linearizableReadLoop","detail":"{readStateIndex:27614; appliedIndex:27608; }","duration":"556.466594ms","start":"2026-10-09T11:50:08.411576Z","end":"2026-10-09T11:50:08.968043Z","steps":["trace[161113328] 'read index received' (duration: 140.795641ms)","trace[161113328] 'applied index is now lower than readState.Index' (duration: 415.665193ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:08.968287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.177658Z","time spent":"790.535717ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:08.968315Z","caller":"traceutil/trace.go:171","msg":"trace[1714688265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26738; }","duration":"556.740539ms","start":"2026-10-09T11:50:08.411562Z","end":"2026-10-09T11:50:08.968302Z","steps":["trace[1714688265] 'agreement among raft nodes before linearized reading' (duration: 556.552526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.968351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.411483Z","time spent":"556.860442ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T11:50:08.96841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.445236ms","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-10-09T11:50:08.968454Z","caller":"traceutil/trace.go:171","msg":"trace[1101920262] 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:26738; }","duration":"427.533038ms","start":"2026-10-09T11:50:08.540909Z","end":"2026-10-09T11:50:08.968442Z","steps":["trace[1101920262] 'agreement among raft nodes before linearized reading' (duration: 427.454187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.968491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.540886Z","time spent":"427.59619ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:08.9685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.862581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T11:50:08.968533Z","caller":"traceutil/trace.go:171","msg":"trace[858348740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26738; }","duration":"222.916042ms","start":"2026-10-09T11:50:08.745606Z","end":"2026-10-09T11:50:08.968522Z","steps":["trace[858348740] 'agreement among raft nodes before linearized reading' (duration: 222.82026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.968593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.101514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-09T11:50:08.968542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.180009Z","time spent":"788.224164ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:08.968634Z","caller":"traceutil/trace.go:171","msg":"trace[972748808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26738; }","duration":"252.177345ms","start":"2026-10-09T11:50:08.716446Z","end":"2026-10-09T11:50:08.968624Z","steps":["trace[972748808] 'agreement among raft nodes before linearized reading' (duration: 252.023592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.968653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.092944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T11:50:08.968707Z","caller":"traceutil/trace.go:171","msg":"trace[253718678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26738; }","duration":"306.143465ms","start":"2026-10-09T11:50:08.662551Z","end":"2026-10-09T11:50:08.968695Z","steps":["trace[253718678] 'agreement among raft nodes before linearized reading' (duration: 306.036063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.968741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.662493Z","time spent":"306.240447ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-09T11:50:08.967745Z","caller":"traceutil/trace.go:171","msg":"trace[1695848003] transaction","detail":"{read_only:false; response_revision:26734; number_of_response:1; }","duration":"789.984757ms","start":"2026-10-09T11:50:08.177719Z","end":"2026-10-09T11:50:08.967703Z","steps":["trace[1695848003] 'process raft request' (duration: 789.758204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.970275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.177696Z","time spent":"792.527756ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:10.623257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.372831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T11:50:10.623332Z","caller":"traceutil/trace.go:171","msg":"trace[1895819353] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26745; }","duration":"567.493302ms","start":"2026-10-09T11:50:10.05582Z","end":"2026-10-09T11:50:10.623313Z","steps":["trace[1895819353] 'range keys from in-memory index tree' (duration: 567.129576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.623368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:10.055797Z","time spent":"567.562414ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-09T11:50:10.666832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.407021ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T11:50:10.66707Z","caller":"traceutil/trace.go:171","msg":"trace[1733762203] linearizableReadLoop","detail":"{readStateIndex:27624; appliedIndex:27622; }","duration":"566.27722ms","start":"2026-10-09T11:50:10.100775Z","end":"2026-10-09T11:50:10.667052Z","steps":["trace[1733762203] 'read index received' (duration: 251.748687ms)","trace[1733762203] 'applied index is now lower than readState.Index' (duration: 314.527613ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:10.667177Z","caller":"traceutil/trace.go:171","msg":"trace[1685881427] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"584.232589ms","start":"2026-10-09T11:50:10.082921Z","end":"2026-10-09T11:50:10.667154Z","steps":["trace[1685881427] 'process raft request' (duration: 584.007324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.667197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.405822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:10.667305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:10.082897Z","time spent":"584.337021ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:10.667346Z","caller":"traceutil/trace.go:171","msg":"trace[2005539733] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:26746; }","duration":"566.583395ms","start":"2026-10-09T11:50:10.100749Z","end":"2026-10-09T11:50:10.667333Z","steps":["trace[2005539733] 'agreement among raft nodes before linearized reading' (duration: 566.379091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.667386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:10.10074Z","time spent":"566.632586ms","remote":"127.0.0.1:53860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:10.676948Z","caller":"traceutil/trace.go:171","msg":"trace[1174201000] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"118.957469ms","start":"2026-10-09T11:50:10.557967Z","end":"2026-10-09T11:50:10.676924Z","steps":["trace[1174201000] 'process raft request' (duration: 118.435889ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:12.830888Z","caller":"traceutil/trace.go:171","msg":"trace[394462294] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"158.376492ms","start":"2026-10-09T11:50:12.672487Z","end":"2026-10-09T11:50:12.830864Z","steps":["trace[394462294] 'process raft request' (duration: 158.21225ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:13.049037Z","caller":"traceutil/trace.go:171","msg":"trace[1889574906] linearizableReadLoop","detail":"{readStateIndex:27641; appliedIndex:27639; }","duration":"359.483773ms","start":"2026-10-09T11:50:12.689503Z","end":"2026-10-09T11:50:13.048987Z","steps":["trace[1889574906] 'read index received' (duration: 141.295291ms)","trace[1889574906] 'applied index is now lower than readState.Index' (duration: 218.187262ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:13.049091Z","caller":"traceutil/trace.go:171","msg":"trace[1655270335] transaction","detail":"{read_only:false; response_revision:26763; number_of_response:1; }","duration":"367.550926ms","start":"2026-10-09T11:50:12.681508Z","end":"2026-10-09T11:50:13.049059Z","steps":["trace[1655270335] 'process raft request' (duration: 319.821214ms)","trace[1655270335] 'compare' (duration: 47.282673ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:13.049196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.673917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:13.049235Z","caller":"traceutil/trace.go:171","msg":"trace[1790991785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26763; }","duration":"359.759908ms","start":"2026-10-09T11:50:12.689463Z","end":"2026-10-09T11:50:13.049223Z","steps":["trace[1790991785] 'agreement among raft nodes before linearized reading' (duration: 359.657457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.049243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.681479Z","time spent":"367.690808ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:13.049269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.689444Z","time spent":"359.817649ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T11:50:13.359337Z","caller":"traceutil/trace.go:171","msg":"trace[129379841] transaction","detail":"{read_only:false; response_revision:26765; number_of_response:1; }","duration":"378.020313ms","start":"2026-10-09T11:50:12.981293Z","end":"2026-10-09T11:50:13.359314Z","steps":["trace[129379841] 'process raft request' (duration: 377.87685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.359471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.981266Z","time spent":"378.134915ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.35953Z","caller":"traceutil/trace.go:171","msg":"trace[1610969937] transaction","detail":"{read_only:false; response_revision:26764; number_of_response:1; }","duration":"380.841976ms","start":"2026-10-09T11:50:12.97865Z","end":"2026-10-09T11:50:13.359492Z","steps":["trace[1610969937] 'process raft request' (duration: 362.743634ms)","trace[1610969937] 'compare' (duration: 17.534082ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:13.359695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.9786Z","time spent":"381.00471ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.359701Z","caller":"traceutil/trace.go:171","msg":"trace[705300413] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"377.218749ms","start":"2026-10-09T11:50:12.982452Z","end":"2026-10-09T11:50:13.359671Z","steps":["trace[705300413] 'process raft request' (duration: 376.817671ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:13.359785Z","caller":"traceutil/trace.go:171","msg":"trace[691126461] transaction","detail":"{read_only:false; response_revision:26767; number_of_response:1; }","duration":"376.832372ms","start":"2026-10-09T11:50:12.982928Z","end":"2026-10-09T11:50:13.35976Z","steps":["trace[691126461] 'process raft request' (duration: 376.462475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.359871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.982914Z","time spent":"376.913743ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:13.359876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.982425Z","time spent":"377.358551ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.360331Z","caller":"traceutil/trace.go:171","msg":"trace[839369283] linearizableReadLoop","detail":"{readStateIndex:27646; appliedIndex:27641; }","duration":"311.21449ms","start":"2026-10-09T11:50:13.049094Z","end":"2026-10-09T11:50:13.360308Z","steps":["trace[839369283] 'read index received' (duration: 292.314133ms)","trace[839369283] 'applied index is now lower than readState.Index' (duration: 18.898637ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:13.360559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.309001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-09T11:50:13.360587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.615947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T11:50:13.360621Z","caller":"traceutil/trace.go:171","msg":"trace[770575961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26768; }","duration":"348.388433ms","start":"2026-10-09T11:50:13.012217Z","end":"2026-10-09T11:50:13.360606Z","steps":["trace[770575961] 'agreement among raft nodes before linearized reading' (duration: 348.183839ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:13.360637Z","caller":"traceutil/trace.go:171","msg":"trace[1152862605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26768; }","duration":"348.692318ms","start":"2026-10-09T11:50:13.011929Z","end":"2026-10-09T11:50:13.360622Z","steps":["trace[1152862605] 'agreement among raft nodes before linearized reading' (duration: 348.492404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.360675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.01221Z","time spent":"348.450034ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T11:50:13.36068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.01192Z","time spent":"348.749339ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T11:50:13.360723Z","caller":"traceutil/trace.go:171","msg":"trace[1235392377] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"377.660606ms","start":"2026-10-09T11:50:12.983016Z","end":"2026-10-09T11:50:13.360676Z","steps":["trace[1235392377] 'process raft request' (duration: 376.470404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.360762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.832759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T11:50:13.360801Z","caller":"traceutil/trace.go:171","msg":"trace[1070470883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26768; }","duration":"369.901689ms","start":"2026-10-09T11:50:12.990888Z","end":"2026-10-09T11:50:13.36079Z","steps":["trace[1070470883] 'agreement among raft nodes before linearized reading' (duration: 369.778077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.360837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.990876Z","time spent":"369.95395ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T11:50:13.360888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.98299Z","time spent":"377.816019ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:13.36061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.735384ms","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-10-09T11:50:13.36113Z","caller":"traceutil/trace.go:171","msg":"trace[1650654643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26768; }","duration":"345.217443ms","start":"2026-10-09T11:50:13.01583Z","end":"2026-10-09T11:50:13.361048Z","steps":["trace[1650654643] 'agreement among raft nodes before linearized reading' (duration: 344.54184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.361253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.015821Z","time spent":"345.365656ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T11:50:13.393728Z","caller":"traceutil/trace.go:171","msg":"trace[27211745] transaction","detail":"{read_only:false; response_revision:26769; number_of_response:1; }","duration":"319.71169ms","start":"2026-10-09T11:50:13.073998Z","end":"2026-10-09T11:50:13.39371Z","steps":["trace[27211745] 'process raft request' (duration: 319.597688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.393863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.073976Z","time spent":"319.806092ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:13.394311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.996721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:13.394315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.224375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:13.394354Z","caller":"traceutil/trace.go:171","msg":"trace[1236916698] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26769; }","duration":"171.100554ms","start":"2026-10-09T11:50:13.223243Z","end":"2026-10-09T11:50:13.394343Z","steps":["trace[1236916698] 'agreement among raft nodes before linearized reading' (duration: 171.030052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.394459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.109014ms","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-10-09T11:50:13.394468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.412238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:13.394535Z","caller":"traceutil/trace.go:171","msg":"trace[1741336427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26769; }","duration":"288.227726ms","start":"2026-10-09T11:50:13.10629Z","end":"2026-10-09T11:50:13.394518Z","steps":["trace[1741336427] 'agreement among raft nodes before linearized reading' (duration: 287.999722ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:13.39455Z","caller":"traceutil/trace.go:171","msg":"trace[944035099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26769; }","duration":"344.52488ms","start":"2026-10-09T11:50:13.050004Z","end":"2026-10-09T11:50:13.394529Z","steps":["trace[944035099] 'agreement among raft nodes before linearized reading' (duration: 344.392638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.394594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.049991Z","time spent":"344.592921ms","remote":"127.0.0.1:45748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T11:50:13.394516Z","caller":"traceutil/trace.go:171","msg":"trace[147693037] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:26769; }","duration":"217.334717ms","start":"2026-10-09T11:50:13.177022Z","end":"2026-10-09T11:50:13.394357Z","steps":["trace[147693037] 'agreement among raft nodes before linearized reading' (duration: 217.224345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.837372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.440779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:13.837734Z","caller":"traceutil/trace.go:171","msg":"trace[978492913] linearizableReadLoop","detail":"{readStateIndex:27651; appliedIndex:27647; }","duration":"443.45839ms","start":"2026-10-09T11:50:13.394254Z","end":"2026-10-09T11:50:13.837713Z","steps":["trace[978492913] 'read index received' (duration: 243.560332ms)","trace[978492913] 'applied index is now lower than readState.Index' (duration: 199.896888ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:13.837842Z","caller":"traceutil/trace.go:171","msg":"trace[1124850597] transaction","detail":"{read_only:false; response_revision:26772; number_of_response:1; }","duration":"472.365166ms","start":"2026-10-09T11:50:13.365458Z","end":"2026-10-09T11:50:13.837824Z","steps":["trace[1124850597] 'process raft request' (duration: 472.157982ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:13.83785Z","caller":"traceutil/trace.go:171","msg":"trace[1389246234] transaction","detail":"{read_only:false; response_revision:26770; number_of_response:1; }","duration":"473.052799ms","start":"2026-10-09T11:50:13.364774Z","end":"2026-10-09T11:50:13.837827Z","steps":["trace[1389246234] 'process raft request' (duration: 273.049359ms)","trace[1389246234] 'compare' (duration: 199.170634ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:13.837845Z","caller":"traceutil/trace.go:171","msg":"trace[1873916831] transaction","detail":"{read_only:false; response_revision:26771; number_of_response:1; }","duration":"472.418647ms","start":"2026-10-09T11:50:13.365398Z","end":"2026-10-09T11:50:13.837817Z","steps":["trace[1873916831] 'process raft request' (duration: 472.104911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.837917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.365438Z","time spent":"472.434547ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.837956Z","caller":"traceutil/trace.go:171","msg":"trace[1816997874] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"472.294555ms","start":"2026-10-09T11:50:13.365639Z","end":"2026-10-09T11:50:13.837933Z","steps":["trace[1816997874] 'process raft request' (duration: 472.02834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.837982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.364751Z","time spent":"473.15012ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:13.837993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.365376Z","time spent":"472.535639ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:13.838033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.357399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:13.838063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.365614Z","time spent":"472.396827ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.838113Z","caller":"traceutil/trace.go:171","msg":"trace[1753906175] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26773; }","duration":"460.481852ms","start":"2026-10-09T11:50:13.377615Z","end":"2026-10-09T11:50:13.838096Z","steps":["trace[1753906175] 'agreement among raft nodes before linearized reading' (duration: 460.335129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.838173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.377602Z","time spent":"460.560933ms","remote":"127.0.0.1:57612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:14.338206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13861412388252604723,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T11:50:14.586759Z","caller":"traceutil/trace.go:171","msg":"trace[65374439] linearizableReadLoop","detail":"{readStateIndex:27653; appliedIndex:27651; }","duration":"748.957972ms","start":"2026-10-09T11:50:13.83778Z","end":"2026-10-09T11:50:14.586738Z","steps":["trace[65374439] 'read index received' (duration: 689.353876ms)","trace[65374439] 'applied index is now lower than readState.Index' (duration: 59.603366ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:14.587014Z","caller":"traceutil/trace.go:171","msg":"trace[2112201401] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"1.187887216s","start":"2026-10-09T11:50:13.399112Z","end":"2026-10-09T11:50:14.586999Z","steps":["trace[2112201401] 'process raft request' (duration: 1.128009665s)","trace[2112201401] 'compare' (duration: 58.724129ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:14.587115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.399088Z","time spent":"1.187952586s","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:14.587236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082144648s","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":"warn","ts":"2026-10-09T11:50:14.587305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.890781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:14.587389Z","caller":"traceutil/trace.go:171","msg":"trace[1392372512] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26775; }","duration":"813.031243ms","start":"2026-10-09T11:50:13.774337Z","end":"2026-10-09T11:50:14.587368Z","steps":["trace[1392372512] 'agreement among raft nodes before linearized reading' (duration: 812.860939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.587408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.707354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:14.587445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.774316Z","time spent":"813.113115ms","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:14.587452Z","caller":"traceutil/trace.go:171","msg":"trace[1917798424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26775; }","duration":"896.796046ms","start":"2026-10-09T11:50:13.690643Z","end":"2026-10-09T11:50:14.587439Z","steps":["trace[1917798424] 'agreement among raft nodes before linearized reading' (duration: 896.717365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.587492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.690583Z","time spent":"896.898108ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T11:50:14.587575Z","caller":"traceutil/trace.go:171","msg":"trace[1434573564] transaction","detail":"{read_only:false; response_revision:26775; number_of_response:1; }","duration":"780.726282ms","start":"2026-10-09T11:50:13.80682Z","end":"2026-10-09T11:50:14.587547Z","steps":["trace[1434573564] 'process raft request' (duration: 779.820725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.5876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110347171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T11:50:14.58733Z","caller":"traceutil/trace.go:171","msg":"trace[1480168138] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26775; }","duration":"1.082281571s","start":"2026-10-09T11:50:13.505022Z","end":"2026-10-09T11:50:14.587304Z","steps":["trace[1480168138] 'agreement among raft nodes before linearized reading' (duration: 1.082087187s)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:14.587658Z","caller":"traceutil/trace.go:171","msg":"trace[294623361] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26775; }","duration":"1.110411792s","start":"2026-10-09T11:50:13.477232Z","end":"2026-10-09T11:50:14.587644Z","steps":["trace[294623361] 'agreement among raft nodes before linearized reading' (duration: 1.11029294s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.587684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.505009Z","time spent":"1.082663238s","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:14.587674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.780742ms","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":"warn","ts":"2026-10-09T11:50:14.587707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.140371ms","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":"warn","ts":"2026-10-09T11:50:14.587712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.477212Z","time spent":"1.110486153s","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:14.58774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.8068Z","time spent":"780.844604ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:14.587786Z","caller":"traceutil/trace.go:171","msg":"trace[316513352] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26775; }","duration":"373.333465ms","start":"2026-10-09T11:50:14.214411Z","end":"2026-10-09T11:50:14.587744Z","steps":["trace[316513352] 'agreement among raft nodes before linearized reading' (duration: 373.192132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.587868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:14.21439Z","time spent":"373.460717ms","remote":"127.0.0.1:57886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:14.587764Z","caller":"traceutil/trace.go:171","msg":"trace[1459427760] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26775; }","duration":"654.916825ms","start":"2026-10-09T11:50:13.932822Z","end":"2026-10-09T11:50:14.587739Z","steps":["trace[1459427760] 'agreement among raft nodes before linearized reading' (duration: 654.762552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.587967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.932795Z","time spent":"655.15206ms","remote":"127.0.0.1:33638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:14.849223Z","caller":"traceutil/trace.go:171","msg":"trace[241336069] linearizableReadLoop","detail":"{readStateIndex:27654; appliedIndex:27653; }","duration":"156.494448ms","start":"2026-10-09T11:50:14.692686Z","end":"2026-10-09T11:50:14.84918Z","steps":["trace[241336069] 'read index received' (duration: 89.567632ms)","trace[241336069] 'applied index is now lower than readState.Index' (duration: 66.923536ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:14.849402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.68696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:14.849457Z","caller":"traceutil/trace.go:171","msg":"trace[791864943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26776; }","duration":"156.796753ms","start":"2026-10-09T11:50:14.69264Z","end":"2026-10-09T11:50:14.849436Z","steps":["trace[791864943] 'agreement among raft nodes before linearized reading' (duration: 156.668821ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:29.852765Z","caller":"traceutil/trace.go:171","msg":"trace[1459078464] transaction","detail":"{read_only:false; response_revision:26892; number_of_response:1; }","duration":"430.409642ms","start":"2026-10-09T11:50:29.422324Z","end":"2026-10-09T11:50:29.852733Z","steps":["trace[1459078464] 'process raft request' (duration: 430.258919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:29.852957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.422306Z","time spent":"430.540665ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:29.888088Z","caller":"traceutil/trace.go:171","msg":"trace[862834448] transaction","detail":"{read_only:false; response_revision:26893; number_of_response:1; }","duration":"440.584195ms","start":"2026-10-09T11:50:29.447479Z","end":"2026-10-09T11:50:29.888063Z","steps":["trace[862834448] 'process raft request' (duration: 439.985494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:29.888254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.447461Z","time spent":"440.690937ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:29.888441Z","caller":"traceutil/trace.go:171","msg":"trace[1860023806] transaction","detail":"{read_only:false; response_revision:26894; number_of_response:1; }","duration":"437.532887ms","start":"2026-10-09T11:50:29.450893Z","end":"2026-10-09T11:50:29.888426Z","steps":["trace[1860023806] 'process raft request' (duration: 436.680581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:29.888519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.450867Z","time spent":"437.615779ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:29.888612Z","caller":"traceutil/trace.go:171","msg":"trace[958044947] transaction","detail":"{read_only:false; response_revision:26895; number_of_response:1; }","duration":"435.925597ms","start":"2026-10-09T11:50:29.452664Z","end":"2026-10-09T11:50:29.88859Z","steps":["trace[958044947] 'process raft request' (duration: 434.951369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:29.88872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.452649Z","time spent":"436.014949ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:29.88899Z","caller":"traceutil/trace.go:171","msg":"trace[184112678] transaction","detail":"{read_only:false; response_revision:26896; number_of_response:1; }","duration":"434.997849ms","start":"2026-10-09T11:50:29.453972Z","end":"2026-10-09T11:50:29.88897Z","steps":["trace[184112678] 'process raft request' (duration: 433.678745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:29.889087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.453954Z","time spent":"435.0897ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:30.648887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.361104ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T11:50:30.648992Z","caller":"traceutil/trace.go:171","msg":"trace[788719706] linearizableReadLoop","detail":"{readStateIndex:27782; appliedIndex:27781; }","duration":"367.310461ms","start":"2026-10-09T11:50:30.281659Z","end":"2026-10-09T11:50:30.64897Z","steps":["trace[788719706] 'read index received' (duration: 32.341µs)","trace[788719706] 'applied index is now lower than readState.Index' (duration: 367.27673ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:30.649284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.608787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:30.649348Z","caller":"traceutil/trace.go:171","msg":"trace[1811161736] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26900; }","duration":"367.718588ms","start":"2026-10-09T11:50:30.281615Z","end":"2026-10-09T11:50:30.649334Z","steps":["trace[1811161736] 'agreement among raft nodes before linearized reading' (duration: 367.569085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.649407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.479033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-10-09T11:50:30.649462Z","caller":"traceutil/trace.go:171","msg":"trace[1322966083] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:26901; }","duration":"214.579616ms","start":"2026-10-09T11:50:30.434868Z","end":"2026-10-09T11:50:30.649447Z","steps":["trace[1322966083] 'agreement among raft nodes before linearized reading' (duration: 214.386092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.649878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.281598Z","time spent":"367.7885ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:35.107836Z","caller":"traceutil/trace.go:171","msg":"trace[1327181475] transaction","detail":"{read_only:false; response_revision:26934; number_of_response:1; }","duration":"118.235925ms","start":"2026-10-09T11:50:34.989571Z","end":"2026-10-09T11:50:35.107806Z","steps":["trace[1327181475] 'process raft request' (duration: 118.062521ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:36.111878Z","caller":"traceutil/trace.go:171","msg":"trace[1359247263] transaction","detail":"{read_only:false; response_revision:26938; number_of_response:1; }","duration":"194.571617ms","start":"2026-10-09T11:50:35.917275Z","end":"2026-10-09T11:50:36.111847Z","steps":["trace[1359247263] 'process raft request' (duration: 103.392714ms)","trace[1359247263] 'compare' (duration: 91.068561ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:36.151807Z","caller":"traceutil/trace.go:171","msg":"trace[167320242] transaction","detail":"{read_only:false; response_revision:26939; number_of_response:1; }","duration":"221.954233ms","start":"2026-10-09T11:50:35.929823Z","end":"2026-10-09T11:50:36.151778Z","steps":["trace[167320242] 'process raft request' (duration: 221.677708ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:36.152042Z","caller":"traceutil/trace.go:171","msg":"trace[4408300] transaction","detail":"{read_only:false; response_revision:26941; number_of_response:1; }","duration":"221.004226ms","start":"2026-10-09T11:50:35.931018Z","end":"2026-10-09T11:50:36.152023Z","steps":["trace[4408300] 'process raft request' (duration: 220.68898ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:36.152374Z","caller":"traceutil/trace.go:171","msg":"trace[566009121] transaction","detail":"{read_only:false; response_revision:26942; number_of_response:1; }","duration":"219.907835ms","start":"2026-10-09T11:50:35.932442Z","end":"2026-10-09T11:50:36.15235Z","steps":["trace[566009121] 'process raft request' (duration: 219.388145ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:36.152545Z","caller":"traceutil/trace.go:171","msg":"trace[344659802] transaction","detail":"{read_only:false; response_revision:26940; number_of_response:1; }","duration":"221.423224ms","start":"2026-10-09T11:50:35.930323Z","end":"2026-10-09T11:50:36.151746Z","steps":["trace[344659802] 'process raft request' (duration: 221.328242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:36.4002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.780157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:36.400534Z","caller":"traceutil/trace.go:171","msg":"trace[1577224785] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"243.860458ms","start":"2026-10-09T11:50:36.156633Z","end":"2026-10-09T11:50:36.400494Z","steps":["trace[1577224785] 'process raft request' (duration: 63.652463ms)","trace[1577224785] 'compare' (duration: 179.481692ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:36.400569Z","caller":"traceutil/trace.go:171","msg":"trace[758678426] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"243.874229ms","start":"2026-10-09T11:50:36.15667Z","end":"2026-10-09T11:50:36.400544Z","steps":["trace[758678426] 'process raft request' (duration: 243.653114ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:36.40085Z","caller":"traceutil/trace.go:171","msg":"trace[1947877957] transaction","detail":"{read_only:false; response_revision:26945; number_of_response:1; }","duration":"244.112943ms","start":"2026-10-09T11:50:36.156721Z","end":"2026-10-09T11:50:36.400834Z","steps":["trace[1947877957] 'process raft request' (duration: 243.761736ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:36.400954Z","caller":"traceutil/trace.go:171","msg":"trace[1596513012] linearizableReadLoop","detail":"{readStateIndex:27829; appliedIndex:27825; }","duration":"116.96884ms","start":"2026-10-09T11:50:36.28396Z","end":"2026-10-09T11:50:36.400929Z","steps":["trace[1596513012] 'read index received' (duration: 40.402314ms)","trace[1596513012] 'applied index is now lower than readState.Index' (duration: 76.564976ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:36.400994Z","caller":"traceutil/trace.go:171","msg":"trace[1495354210] transaction","detail":"{read_only:false; response_revision:26946; number_of_response:1; }","duration":"243.687994ms","start":"2026-10-09T11:50:36.157261Z","end":"2026-10-09T11:50:36.400949Z","steps":["trace[1495354210] 'process raft request' (duration: 243.514101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:36.401277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.278086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T11:50:36.401544Z","caller":"traceutil/trace.go:171","msg":"trace[851571298] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26946; }","duration":"117.582812ms","start":"2026-10-09T11:50:36.2839Z","end":"2026-10-09T11:50:36.401483Z","steps":["trace[851571298] 'agreement among raft nodes before linearized reading' (duration: 117.147443ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:36.672698Z","caller":"traceutil/trace.go:171","msg":"trace[146961980] linearizableReadLoop","detail":"{readStateIndex:27830; appliedIndex:27829; }","duration":"162.100613ms","start":"2026-10-09T11:50:36.510571Z","end":"2026-10-09T11:50:36.672672Z","steps":["trace[146961980] 'read index received' (duration: 123.221568ms)","trace[146961980] 'applied index is now lower than readState.Index' (duration: 38.877865ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:36.672833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.236846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T11:50:36.67281Z","caller":"traceutil/trace.go:171","msg":"trace[2080047377] transaction","detail":"{read_only:false; response_revision:26947; number_of_response:1; }","duration":"167.23964ms","start":"2026-10-09T11:50:36.505496Z","end":"2026-10-09T11:50:36.672736Z","steps":["trace[2080047377] 'process raft request' (duration: 128.364906ms)","trace[2080047377] 'compare' (duration: 38.353674ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:36.672911Z","caller":"traceutil/trace.go:171","msg":"trace[1084183875] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26947; }","duration":"162.327007ms","start":"2026-10-09T11:50:36.510557Z","end":"2026-10-09T11:50:36.672884Z","steps":["trace[1084183875] 'agreement among raft nodes before linearized reading' (duration: 162.196575ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:36.854425Z","caller":"traceutil/trace.go:171","msg":"trace[1199996628] linearizableReadLoop","detail":"{readStateIndex:27831; appliedIndex:27830; }","duration":"173.422707ms","start":"2026-10-09T11:50:36.680974Z","end":"2026-10-09T11:50:36.854397Z","steps":["trace[1199996628] 'read index received' (duration: 121.544767ms)","trace[1199996628] 'applied index is now lower than readState.Index' (duration: 51.87373ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:36.854546Z","caller":"traceutil/trace.go:171","msg":"trace[2035627100] transaction","detail":"{read_only:false; response_revision:26948; number_of_response:1; }","duration":"177.661866ms","start":"2026-10-09T11:50:36.676849Z","end":"2026-10-09T11:50:36.854511Z","steps":["trace[2035627100] 'process raft request' (duration: 125.791466ms)","trace[2035627100] 'compare' (duration: 51.610695ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:36.854652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.235283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:36.854692Z","caller":"traceutil/trace.go:171","msg":"trace[1840888731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26948; }","duration":"164.359185ms","start":"2026-10-09T11:50:36.690317Z","end":"2026-10-09T11:50:36.854676Z","steps":["trace[1840888731] 'agreement among raft nodes before linearized reading' (duration: 164.230753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:36.854701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.649371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:36.854827Z","caller":"traceutil/trace.go:171","msg":"trace[1744326713] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26948; }","duration":"173.842655ms","start":"2026-10-09T11:50:36.680952Z","end":"2026-10-09T11:50:36.854795Z","steps":["trace[1744326713] 'agreement among raft nodes before linearized reading' (duration: 173.560119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:36.855057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.902668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T11:50:36.855087Z","caller":"traceutil/trace.go:171","msg":"trace[1863165029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26948; }","duration":"145.982129ms","start":"2026-10-09T11:50:36.709096Z","end":"2026-10-09T11:50:36.855078Z","steps":["trace[1863165029] 'agreement among raft nodes before linearized reading' (duration: 145.859987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:37.26575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.756699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:37.265841Z","caller":"traceutil/trace.go:171","msg":"trace[1089568851] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26950; }","duration":"104.910242ms","start":"2026-10-09T11:50:37.160905Z","end":"2026-10-09T11:50:37.265816Z","steps":["trace[1089568851] 'count revisions from in-memory index tree' (duration: 104.598386ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:38.252417Z","caller":"traceutil/trace.go:171","msg":"trace[1472885691] transaction","detail":"{read_only:false; response_revision:26952; number_of_response:1; }","duration":"135.247455ms","start":"2026-10-09T11:50:38.117145Z","end":"2026-10-09T11:50:38.252393Z","steps":["trace[1472885691] 'process raft request' (duration: 135.093432ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.021929Z","caller":"traceutil/trace.go:171","msg":"trace[553987008] linearizableReadLoop","detail":"{readStateIndex:27847; appliedIndex:27846; }","duration":"151.528332ms","start":"2026-10-09T11:50:38.870376Z","end":"2026-10-09T11:50:39.021904Z","steps":["trace[553987008] 'read index received' (duration: 151.290188ms)","trace[553987008] 'applied index is now lower than readState.Index' (duration: 236.814µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:39.022477Z","caller":"traceutil/trace.go:171","msg":"trace[257948950] transaction","detail":"{read_only:false; response_revision:26964; number_of_response:1; }","duration":"162.903758ms","start":"2026-10-09T11:50:38.859542Z","end":"2026-10-09T11:50:39.022446Z","steps":["trace[257948950] 'process raft request' (duration: 162.202014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.022603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.857194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:39.022679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.37962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T11:50:39.022716Z","caller":"traceutil/trace.go:171","msg":"trace[416939033] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:26964; }","duration":"102.014588ms","start":"2026-10-09T11:50:38.920681Z","end":"2026-10-09T11:50:39.022696Z","steps":["trace[416939033] 'agreement among raft nodes before linearized reading' (duration: 101.837864ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.022779Z","caller":"traceutil/trace.go:171","msg":"trace[1488913267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26964; }","duration":"133.562384ms","start":"2026-10-09T11:50:38.889191Z","end":"2026-10-09T11:50:39.022753Z","steps":["trace[1488913267] 'agreement among raft nodes before linearized reading' (duration: 133.239677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.022556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.172404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-zvjsg\" ","response":"range_response_count:1 size:3397"} {"level":"info","ts":"2026-10-09T11:50:39.023376Z","caller":"traceutil/trace.go:171","msg":"trace[885484138] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-zvjsg; range_end:; response_count:1; response_revision:26964; }","duration":"153.006341ms","start":"2026-10-09T11:50:38.870306Z","end":"2026-10-09T11:50:39.023313Z","steps":["trace[885484138] 'agreement among raft nodes before linearized reading' (duration: 152.114283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.297473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.984286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:39.297572Z","caller":"traceutil/trace.go:171","msg":"trace[1560481191] transaction","detail":"{read_only:false; response_revision:26965; number_of_response:1; }","duration":"271.041981ms","start":"2026-10-09T11:50:39.026511Z","end":"2026-10-09T11:50:39.297553Z","steps":["trace[1560481191] 'process raft request' (duration: 149.896012ms)","trace[1560481191] 'compare' (duration: 120.856484ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:39.545747Z","caller":"traceutil/trace.go:171","msg":"trace[1958826154] transaction","detail":"{read_only:false; response_revision:26966; number_of_response:1; }","duration":"514.305706ms","start":"2026-10-09T11:50:39.03141Z","end":"2026-10-09T11:50:39.545716Z","steps":["trace[1958826154] 'process raft request' (duration: 513.939189ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.545758Z","caller":"traceutil/trace.go:171","msg":"trace[45237311] linearizableReadLoop","detail":"{readStateIndex:27849; appliedIndex:27847; }","duration":"455.859681ms","start":"2026-10-09T11:50:39.08987Z","end":"2026-10-09T11:50:39.54573Z","steps":["trace[45237311] 'read index received' (duration: 86.553725ms)","trace[45237311] 'applied index is now lower than readState.Index' (duration: 369.304836ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:39.545834Z","caller":"traceutil/trace.go:171","msg":"trace[297085351] transaction","detail":"{read_only:false; response_revision:26967; number_of_response:1; }","duration":"421.843919ms","start":"2026-10-09T11:50:39.123965Z","end":"2026-10-09T11:50:39.545809Z","steps":["trace[297085351] 'process raft request' (duration: 421.618564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.545978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.095047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:39.545962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.031404Z","time spent":"514.415208ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3696,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:39.546039Z","caller":"traceutil/trace.go:171","msg":"trace[421621609] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26967; }","duration":"456.215969ms","start":"2026-10-09T11:50:39.089806Z","end":"2026-10-09T11:50:39.546022Z","steps":["trace[421621609] 'agreement among raft nodes before linearized reading' (duration: 456.101097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.546074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.089783Z","time spent":"456.28143ms","remote":"127.0.0.1:57654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:39.546088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.123939Z","time spent":"421.95274ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:39.584083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.679888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:39.584096Z","caller":"traceutil/trace.go:171","msg":"trace[1487218460] transaction","detail":"{read_only:false; response_revision:26968; number_of_response:1; }","duration":"282.383044ms","start":"2026-10-09T11:50:39.301675Z","end":"2026-10-09T11:50:39.584058Z","steps":["trace[1487218460] 'process raft request' (duration: 282.123269ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.584191Z","caller":"traceutil/trace.go:171","msg":"trace[93614639] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:26968; }","duration":"221.830141ms","start":"2026-10-09T11:50:39.362323Z","end":"2026-10-09T11:50:39.584153Z","steps":["trace[93614639] 'agreement among raft nodes before linearized reading' (duration: 221.645917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.584183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.111997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:39.584392Z","caller":"traceutil/trace.go:171","msg":"trace[803417492] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26968; }","duration":"185.348682ms","start":"2026-10-09T11:50:39.399021Z","end":"2026-10-09T11:50:39.584369Z","steps":["trace[803417492] 'agreement among raft nodes before linearized reading' (duration: 185.043486ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.713134Z","caller":"traceutil/trace.go:171","msg":"trace[535752716] transaction","detail":"{read_only:false; response_revision:26969; number_of_response:1; }","duration":"160.689445ms","start":"2026-10-09T11:50:39.552417Z","end":"2026-10-09T11:50:39.713107Z","steps":["trace[535752716] 'process raft request' (duration: 160.409319ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.71338Z","caller":"traceutil/trace.go:171","msg":"trace[373238043] linearizableReadLoop","detail":"{readStateIndex:27853; appliedIndex:27851; }","duration":"122.355041ms","start":"2026-10-09T11:50:39.591Z","end":"2026-10-09T11:50:39.713355Z","steps":["trace[373238043] 'read index received' (duration: 121.844991ms)","trace[373238043] 'applied index is now lower than readState.Index' (duration: 508.93µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:39.713552Z","caller":"traceutil/trace.go:171","msg":"trace[1344350059] transaction","detail":"{read_only:false; response_revision:26970; number_of_response:1; }","duration":"160.773457ms","start":"2026-10-09T11:50:39.552767Z","end":"2026-10-09T11:50:39.71354Z","steps":["trace[1344350059] 'process raft request' (duration: 160.281158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.71365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.661027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:39.71369Z","caller":"traceutil/trace.go:171","msg":"trace[734857831] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26970; }","duration":"122.746769ms","start":"2026-10-09T11:50:39.59093Z","end":"2026-10-09T11:50:39.713677Z","steps":["trace[734857831] 'agreement among raft nodes before linearized reading' (duration: 122.663777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:40.045465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.300837ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T11:50:40.045675Z","caller":"traceutil/trace.go:171","msg":"trace[424771525] linearizableReadLoop","detail":"{readStateIndex:27854; appliedIndex:27853; }","duration":"332.218386ms","start":"2026-10-09T11:50:39.71344Z","end":"2026-10-09T11:50:40.045658Z","steps":["trace[424771525] 'read index received' (duration: 89.630743ms)","trace[424771525] 'applied index is now lower than readState.Index' (duration: 242.586683ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:40.045763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.437664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:40.045816Z","caller":"traceutil/trace.go:171","msg":"trace[824900897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26970; }","duration":"355.538627ms","start":"2026-10-09T11:50:39.690261Z","end":"2026-10-09T11:50:40.0458Z","steps":["trace[824900897] 'agreement among raft nodes before linearized reading' (duration: 355.437745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:40.045818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.519414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:40.045858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.690239Z","time spent":"355.608848ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T11:50:40.045876Z","caller":"traceutil/trace.go:171","msg":"trace[591858938] transaction","detail":"{read_only:false; response_revision:26971; number_of_response:1; }","duration":"103.320652ms","start":"2026-10-09T11:50:39.94253Z","end":"2026-10-09T11:50:40.04585Z","steps":["trace[591858938] 'process raft request' (duration: 103.037266ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:40.045907Z","caller":"traceutil/trace.go:171","msg":"trace[1182154704] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26970; }","duration":"447.634946ms","start":"2026-10-09T11:50:39.598253Z","end":"2026-10-09T11:50:40.045888Z","steps":["trace[1182154704] 'agreement among raft nodes before linearized reading' (duration: 447.462353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:40.045953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.598192Z","time spent":"447.748488ms","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:40.124814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:40.1249Z","caller":"traceutil/trace.go:171","msg":"trace[635210899] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26971; }","duration":"406.182183ms","start":"2026-10-09T11:50:39.718695Z","end":"2026-10-09T11:50:40.124877Z","steps":["trace[635210899] 'agreement among raft nodes before linearized reading' (duration: 327.128789ms)","trace[635210899] 'range keys from in-memory index tree' (duration: 78.919011ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:40.124886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.723975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-zvjsg\" ","response":"range_response_count:1 size:3714"} {"level":"warn","ts":"2026-10-09T11:50:40.124953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.718675Z","time spent":"406.266365ms","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-10-09T11:50:40.124967Z","caller":"traceutil/trace.go:171","msg":"trace[1166405119] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-zvjsg; range_end:; response_count:1; response_revision:26971; }","duration":"233.820237ms","start":"2026-10-09T11:50:39.891123Z","end":"2026-10-09T11:50:40.124943Z","steps":["trace[1166405119] 'agreement among raft nodes before linearized reading' (duration: 154.937437ms)","trace[1166405119] 'range keys from in-memory index tree' (duration: 78.672416ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:40.282911Z","caller":"traceutil/trace.go:171","msg":"trace[1600454959] transaction","detail":"{read_only:false; response_revision:26972; number_of_response:1; }","duration":"149.170888ms","start":"2026-10-09T11:50:40.133728Z","end":"2026-10-09T11:50:40.282899Z","steps":["trace[1600454959] 'process raft request' (duration: 149.069366ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:51.123774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24909} {"level":"info","ts":"2026-10-09T11:51:51.273269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24909,"took":"145.295254ms","hash":219840585,"current-db-size-bytes":60104704,"current-db-size":"60 MB","current-db-size-in-use-bytes":36302848,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-09T11:51:51.273337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":219840585,"revision":24909,"compact-revision":21682} {"level":"info","ts":"2026-10-09T11:54:16.424637Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T11:54:16.43198Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T11:54:16.432238Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T11:54:25.85924Z","caller":"traceutil/trace.go:171","msg":"trace[233094363] transaction","detail":"{read_only:false; response_revision:29144; number_of_response:1; }","duration":"103.118118ms","start":"2026-10-09T11:54:25.756092Z","end":"2026-10-09T11:54:25.85921Z","steps":["trace[233094363] 'process raft request' (duration: 102.415685ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:56:51.130761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27681} {"level":"info","ts":"2026-10-09T11:56:51.25774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27681,"took":"122.890491ms","hash":943906235,"current-db-size-bytes":60104704,"current-db-size":"60 MB","current-db-size-in-use-bytes":36802560,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-09T11:56:51.257804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":943906235,"revision":27681,"compact-revision":24909} {"level":"info","ts":"2026-10-09T12:01:51.138147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30375} {"level":"info","ts":"2026-10-09T12:01:51.307703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30375,"took":"162.526538ms","hash":406415744,"current-db-size-bytes":60104704,"current-db-size":"60 MB","current-db-size-in-use-bytes":32100352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T12:01:51.307779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":406415744,"revision":30375,"compact-revision":27681} {"level":"info","ts":"2026-10-09T12:06:51.145536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32728} {"level":"info","ts":"2026-10-09T12:06:51.311106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32728,"took":"160.956399ms","hash":2542079917,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":31862784,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T12:06:51.311179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2542079917,"revision":32728,"compact-revision":30375} {"level":"info","ts":"2026-10-09T12:11:51.152341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34986} {"level":"info","ts":"2026-10-09T12:11:51.284332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34986,"took":"126.59772ms","hash":1354255339,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":32382976,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T12:11:51.284396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1354255339,"revision":34986,"compact-revision":32728} {"level":"info","ts":"2026-10-09T12:15:09.883809Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T12:15:09.891289Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T12:15:09.891774Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T12:16:51.159598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37286} {"level":"info","ts":"2026-10-09T12:16:51.291838Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37286,"took":"122.499042ms","hash":3046075289,"current-db-size-bytes":61997056,"current-db-size":"62 MB","current-db-size-in-use-bytes":33239040,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T12:16:51.291928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3046075289,"revision":37286,"compact-revision":34986} {"level":"info","ts":"2026-10-09T12:21:51.166461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39560} {"level":"info","ts":"2026-10-09T12:21:51.302253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39560,"took":"131.425322ms","hash":4022400264,"current-db-size-bytes":61997056,"current-db-size":"62 MB","current-db-size-in-use-bytes":30478336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T12:21:51.302317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4022400264,"revision":39560,"compact-revision":37286} {"level":"info","ts":"2026-10-09T12:26:51.173492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41787} {"level":"info","ts":"2026-10-09T12:26:51.304028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41787,"took":"126.476557ms","hash":1491568029,"current-db-size-bytes":61997056,"current-db-size":"62 MB","current-db-size-in-use-bytes":29974528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T12:26:51.304158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1491568029,"revision":41787,"compact-revision":39560} {"level":"info","ts":"2026-10-09T12:31:51.181759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":44014} {"level":"info","ts":"2026-10-09T12:31:51.31239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":44014,"took":"126.283495ms","hash":1630861418,"current-db-size-bytes":61997056,"current-db-size":"62 MB","current-db-size-in-use-bytes":29794304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T12:31:51.312493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1630861418,"revision":44014,"compact-revision":41787}