{"level":"warn","ts":"2026-09-07T10:47:02.576598Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:47:02.576684Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.166: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.166:2380","--initial-cluster=instance=https://162.253.55.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.166:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-07T10:47:02.576744Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:47:02.576751Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-09-07T10:47:02.576765Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T10:47:02.577553Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"]} {"level":"info","ts":"2026-09-07T10:47:02.57765Z","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.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.166:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-07T10:47:02.581346Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.405245ms"} {"level":"info","ts":"2026-09-07T10:47:02.587865Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7481851e1bcdf3c","cluster-id":"bb367d182eb83f54"} {"level":"info","ts":"2026-09-07T10:47:02.588027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T10:47:02.58806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 0"} {"level":"info","ts":"2026-09-07T10:47:02.588081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7481851e1bcdf3c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T10:47:02.588099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 1"} {"level":"info","ts":"2026-09-07T10:47:02.588152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"warn","ts":"2026-09-07T10:47:02.592875Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T10:47:02.595662Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T10:47:02.597311Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-07T10:47:02.599894Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7481851e1bcdf3c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T10:47:02.600547Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T10:47:02.600526Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7481851e1bcdf3c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T10:47:02.600629Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T10:47:02.600705Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T10:47:02.60526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"info","ts":"2026-09-07T10:47:02.605371Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","added-peer-id":"b7481851e1bcdf3c","added-peer-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-09-07T10:47:02.605744Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T10:47:02.605887Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-09-07T10:47:02.605946Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-09-07T10:47:02.606001Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7481851e1bcdf3c","initial-advertise-peer-urls":["https://162.253.55.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T10:47:02.606034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T10:47:03.48878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T10:47:03.488839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T10:47:03.488885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgPreVoteResp from b7481851e1bcdf3c at term 1"} {"level":"info","ts":"2026-09-07T10:47:03.488903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became candidate at term 2"} {"level":"info","ts":"2026-09-07T10:47:03.488921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgVoteResp from b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-09-07T10:47:03.488935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became leader at term 2"} {"level":"info","ts":"2026-09-07T10:47:03.488947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7481851e1bcdf3c elected leader b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-09-07T10:47:03.490346Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7481851e1bcdf3c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.166:2379]}","request-path":"/0/members/b7481851e1bcdf3c/attributes","cluster-id":"bb367d182eb83f54","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T10:47:03.490536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:47:03.49073Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T10:47:03.490707Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:47:03.490768Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T10:47:03.490387Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:47:03.49198Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:47:03.492131Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:47:03.492218Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:47:03.493169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T10:47:03.493803Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.166:2379"} {"level":"warn","ts":"2026-09-07T10:47:47.884342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.121345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:47:47.884608Z","caller":"traceutil/trace.go:171","msg":"trace[491665628] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:585; }","duration":"244.484312ms","start":"2026-09-07T10:47:47.640089Z","end":"2026-09-07T10:47:47.884573Z","steps":["trace[491665628] 'range keys from in-memory index tree' (duration: 243.954702ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:48.300889Z","caller":"traceutil/trace.go:171","msg":"trace[714155905] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"325.710854ms","start":"2026-09-07T10:47:47.975151Z","end":"2026-09-07T10:47:48.300862Z","steps":["trace[714155905] 'read index received' (duration: 325.468219ms)","trace[714155905] 'applied index is now lower than readState.Index' (duration: 241.375µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:47:48.301154Z","caller":"traceutil/trace.go:171","msg":"trace[197475656] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"388.673644ms","start":"2026-09-07T10:47:47.912394Z","end":"2026-09-07T10:47:48.301068Z","steps":["trace[197475656] 'process raft request' (duration: 388.295076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:47:48.301295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.120362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:47:48.301352Z","caller":"traceutil/trace.go:171","msg":"trace[607391954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"326.242945ms","start":"2026-09-07T10:47:47.975093Z","end":"2026-09-07T10:47:48.301336Z","steps":["trace[607391954] 'agreement among raft nodes before linearized reading' (duration: 326.143343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:47:48.301392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:47:47.975066Z","time spent":"326.317197ms","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T10:47:48.301656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:47:47.912341Z","time spent":"388.889579ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:47:48.373541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.404747ms","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-09-07T10:47:48.373542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.61612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-07T10:47:48.373918Z","caller":"traceutil/trace.go:171","msg":"trace[689982533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"135.07441ms","start":"2026-09-07T10:47:48.23881Z","end":"2026-09-07T10:47:48.373885Z","steps":["trace[689982533] 'agreement among raft nodes before linearized reading' (duration: 134.467677ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:48.373773Z","caller":"traceutil/trace.go:171","msg":"trace[1293923580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"396.669411ms","start":"2026-09-07T10:47:47.976985Z","end":"2026-09-07T10:47:48.373654Z","steps":["trace[1293923580] 'agreement among raft nodes before linearized reading' (duration: 396.405996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:47:48.374208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:47:47.976952Z","time spent":"397.158152ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-07T10:49:24.477611Z","caller":"traceutil/trace.go:171","msg":"trace[1019077544] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"123.442595ms","start":"2026-09-07T10:49:24.354141Z","end":"2026-09-07T10:49:24.477583Z","steps":["trace[1019077544] 'read index received' (duration: 123.085898ms)","trace[1019077544] 'applied index is now lower than readState.Index' (duration: 355.777µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:24.477671Z","caller":"traceutil/trace.go:171","msg":"trace[937826759] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"149.862562ms","start":"2026-09-07T10:49:24.327786Z","end":"2026-09-07T10:49:24.477649Z","steps":["trace[937826759] 'process raft request' (duration: 149.578076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:24.477879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.72343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ce244046-e853-4a70-880d-324b47c67f48\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-07T10:49:24.47795Z","caller":"traceutil/trace.go:171","msg":"trace[1518653686] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ce244046-e853-4a70-880d-324b47c67f48; range_end:; response_count:1; response_revision:1849; }","duration":"123.838913ms","start":"2026-09-07T10:49:24.354092Z","end":"2026-09-07T10:49:24.477931Z","steps":["trace[1518653686] 'agreement among raft nodes before linearized reading' (duration: 123.618029ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:24.590151Z","caller":"traceutil/trace.go:171","msg":"trace[2033581282] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"103.46752ms","start":"2026-09-07T10:49:24.486646Z","end":"2026-09-07T10:49:24.590113Z","steps":["trace[2033581282] 'process raft request' (duration: 71.947461ms)","trace[2033581282] 'compare' (duration: 31.310955ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:37.952331Z","caller":"traceutil/trace.go:171","msg":"trace[1533382075] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"179.457557ms","start":"2026-09-07T10:49:37.772841Z","end":"2026-09-07T10:49:37.952298Z","steps":["trace[1533382075] 'process raft request' (duration: 166.332482ms)","trace[1533382075] 'compare' (duration: 12.990562ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:37.95619Z","caller":"traceutil/trace.go:171","msg":"trace[955830787] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"181.578463ms","start":"2026-09-07T10:49:37.774591Z","end":"2026-09-07T10:49:37.956169Z","steps":["trace[955830787] 'process raft request' (duration: 181.4485ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:09.106073Z","caller":"traceutil/trace.go:171","msg":"trace[816777830] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"121.207132ms","start":"2026-09-07T10:50:08.984829Z","end":"2026-09-07T10:50:09.106036Z","steps":["trace[816777830] 'process raft request' (duration: 47.758767ms)","trace[816777830] 'compare' (duration: 73.178389ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:50:15.337538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.462873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:50:15.337707Z","caller":"traceutil/trace.go:171","msg":"trace[2090249165] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"212.753199ms","start":"2026-09-07T10:50:15.124931Z","end":"2026-09-07T10:50:15.337684Z","steps":["trace[2090249165] 'process raft request' (duration: 92.852166ms)","trace[2090249165] 'compare' (duration: 119.240469ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:50:15.367214Z","caller":"traceutil/trace.go:171","msg":"trace[475371576] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2259; }","duration":"202.082779ms","start":"2026-09-07T10:50:15.165066Z","end":"2026-09-07T10:50:15.367149Z","steps":["trace[475371576] 'read index received' (duration: 52.691014ms)","trace[475371576] 'applied index is now lower than readState.Index' (duration: 149.390625ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:50:15.367487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.402725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-07T10:50:15.367531Z","caller":"traceutil/trace.go:171","msg":"trace[954691240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2177; }","duration":"202.500127ms","start":"2026-09-07T10:50:15.165018Z","end":"2026-09-07T10:50:15.367518Z","steps":["trace[954691240] 'agreement among raft nodes before linearized reading' (duration: 202.310073ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:15.367306Z","caller":"traceutil/trace.go:171","msg":"trace[1545961527] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"240.121425ms","start":"2026-09-07T10:50:15.127128Z","end":"2026-09-07T10:50:15.367249Z","steps":["trace[1545961527] 'process raft request' (duration: 239.872189ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:15.368077Z","caller":"traceutil/trace.go:171","msg":"trace[1159815095] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"124.709648ms","start":"2026-09-07T10:50:15.243354Z","end":"2026-09-07T10:50:15.368063Z","steps":["trace[1159815095] 'process raft request' (duration: 124.463283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:15.368276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.058628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T10:50:15.368391Z","caller":"traceutil/trace.go:171","msg":"trace[854326571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2178; }","duration":"173.235472ms","start":"2026-09-07T10:50:15.195131Z","end":"2026-09-07T10:50:15.368366Z","steps":["trace[854326571] 'agreement among raft nodes before linearized reading' (duration: 172.865024ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:20.633345Z","caller":"traceutil/trace.go:171","msg":"trace[574644932] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"123.371759ms","start":"2026-09-07T10:50:20.509935Z","end":"2026-09-07T10:50:20.633307Z","steps":["trace[574644932] 'process raft request' (duration: 123.200905ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:07.786962Z","caller":"traceutil/trace.go:171","msg":"trace[303429496] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"218.709763ms","start":"2026-09-07T10:51:07.568215Z","end":"2026-09-07T10:51:07.786925Z","steps":["trace[303429496] 'process raft request' (duration: 218.441168ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:07.788444Z","caller":"traceutil/trace.go:171","msg":"trace[1265890566] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"218.814436ms","start":"2026-09-07T10:51:07.569595Z","end":"2026-09-07T10:51:07.78841Z","steps":["trace[1265890566] 'process raft request' (duration: 218.597621ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:59.453376Z","caller":"traceutil/trace.go:171","msg":"trace[300529919] transaction","detail":"{read_only:false; response_revision:4082; number_of_response:1; }","duration":"112.051391ms","start":"2026-09-07T10:54:59.3413Z","end":"2026-09-07T10:54:59.453351Z","steps":["trace[300529919] 'process raft request' (duration: 111.890358ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:55:04.91101Z","caller":"traceutil/trace.go:171","msg":"trace[378360574] transaction","detail":"{read_only:false; response_revision:4109; number_of_response:1; }","duration":"131.533453ms","start":"2026-09-07T10:55:04.779452Z","end":"2026-09-07T10:55:04.910985Z","steps":["trace[378360574] 'process raft request' (duration: 131.39281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:37.804413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.540202ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:56:37.804579Z","caller":"traceutil/trace.go:171","msg":"trace[1545039248] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5321; }","duration":"252.717206ms","start":"2026-09-07T10:56:37.551837Z","end":"2026-09-07T10:56:37.804555Z","steps":["trace[1545039248] 'range keys from in-memory index tree' (duration: 252.516962ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:37.804617Z","caller":"traceutil/trace.go:171","msg":"trace[575671176] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"360.327524ms","start":"2026-09-07T10:56:37.444262Z","end":"2026-09-07T10:56:37.80459Z","steps":["trace[575671176] 'process raft request' (duration: 285.47156ms)","trace[575671176] 'compare' (duration: 74.525447ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:56:37.804735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:37.444238Z","time spent":"360.428685ms","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3290,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:56:37.823702Z","caller":"traceutil/trace.go:171","msg":"trace[133454470] linearizableReadLoop","detail":"{readStateIndex:5525; appliedIndex:5523; }","duration":"326.646141ms","start":"2026-09-07T10:56:37.49703Z","end":"2026-09-07T10:56:37.823676Z","steps":["trace[133454470] 'read index received' (duration: 232.725293ms)","trace[133454470] 'applied index is now lower than readState.Index' (duration: 93.919938ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:37.823773Z","caller":"traceutil/trace.go:171","msg":"trace[1276116595] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"359.558326ms","start":"2026-09-07T10:56:37.464186Z","end":"2026-09-07T10:56:37.823745Z","steps":["trace[1276116595] 'process raft request' (duration: 359.311601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:37.823855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.802464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T10:56:37.823909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:37.46416Z","time spent":"359.679449ms","remote":"127.0.0.1:38438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58177,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:56:37.8239Z","caller":"traceutil/trace.go:171","msg":"trace[329489819] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5323; }","duration":"326.905516ms","start":"2026-09-07T10:56:37.496981Z","end":"2026-09-07T10:56:37.823887Z","steps":["trace[329489819] 'agreement among raft nodes before linearized reading' (duration: 326.809804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:37.823972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:37.496961Z","time spent":"326.996018ms","remote":"127.0.0.1:38812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-09-07T10:56:37.866398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.163402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1219a346-78c6-4f31-a002-39a47b335fd5\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-09-07T10:56:37.866456Z","caller":"traceutil/trace.go:171","msg":"trace[28920826] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1219a346-78c6-4f31-a002-39a47b335fd5; range_end:; response_count:1; response_revision:5324; }","duration":"353.254333ms","start":"2026-09-07T10:56:37.513187Z","end":"2026-09-07T10:56:37.866442Z","steps":["trace[28920826] 'agreement among raft nodes before linearized reading' (duration: 353.09572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:37.866485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:37.513177Z","time spent":"353.302424ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3625,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1219a346-78c6-4f31-a002-39a47b335fd5\" "} {"level":"warn","ts":"2026-09-07T10:56:37.866495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.159719ms","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-09-07T10:56:37.866533Z","caller":"traceutil/trace.go:171","msg":"trace[1614117033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5324; }","duration":"282.222541ms","start":"2026-09-07T10:56:37.584296Z","end":"2026-09-07T10:56:37.866519Z","steps":["trace[1614117033] 'agreement among raft nodes before linearized reading' (duration: 282.142459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:37.866545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.558082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l4m7r\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T10:56:37.866643Z","caller":"traceutil/trace.go:171","msg":"trace[1107502118] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l4m7r; range_end:; response_count:1; response_revision:5324; }","duration":"122.724236ms","start":"2026-09-07T10:56:37.743894Z","end":"2026-09-07T10:56:37.866618Z","steps":["trace[1107502118] 'agreement among raft nodes before linearized reading' (duration: 122.368778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:37.866671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.979932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:56:37.866691Z","caller":"traceutil/trace.go:171","msg":"trace[614470689] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5324; }","duration":"339.023342ms","start":"2026-09-07T10:56:37.527661Z","end":"2026-09-07T10:56:37.866685Z","steps":["trace[614470689] 'agreement among raft nodes before linearized reading' (duration: 338.982741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:37.866707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:37.52765Z","time spent":"339.052243ms","remote":"127.0.0.1:38650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-07T10:56:37.866398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.820731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T10:56:37.866836Z","caller":"traceutil/trace.go:171","msg":"trace[2070509507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5324; }","duration":"272.28754ms","start":"2026-09-07T10:56:37.594528Z","end":"2026-09-07T10:56:37.866816Z","steps":["trace[2070509507] 'agreement among raft nodes before linearized reading' (duration: 271.732148ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:50.582875Z","caller":"traceutil/trace.go:171","msg":"trace[165705914] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"119.188141ms","start":"2026-09-07T10:56:50.463663Z","end":"2026-09-07T10:56:50.582851Z","steps":["trace[165705914] 'process raft request' (duration: 119.036038ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:51.194511Z","caller":"traceutil/trace.go:171","msg":"trace[634041333] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"263.895612ms","start":"2026-09-07T10:56:50.930592Z","end":"2026-09-07T10:56:51.194487Z","steps":["trace[634041333] 'process raft request' (duration: 263.762349ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:51.206234Z","caller":"traceutil/trace.go:171","msg":"trace[78929157] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"237.419013ms","start":"2026-09-07T10:56:50.968792Z","end":"2026-09-07T10:56:51.206211Z","steps":["trace[78929157] 'process raft request' (duration: 237.220409ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:51.206419Z","caller":"traceutil/trace.go:171","msg":"trace[960615459] linearizableReadLoop","detail":"{readStateIndex:5778; appliedIndex:5775; }","duration":"232.135991ms","start":"2026-09-07T10:56:50.974265Z","end":"2026-09-07T10:56:51.206401Z","steps":["trace[960615459] 'read index received' (duration: 220.113697ms)","trace[960615459] 'applied index is now lower than readState.Index' (duration: 12.021264ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:51.206502Z","caller":"traceutil/trace.go:171","msg":"trace[1768819805] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"233.05121ms","start":"2026-09-07T10:56:50.97344Z","end":"2026-09-07T10:56:51.206491Z","steps":["trace[1768819805] 'process raft request' (duration: 232.718643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:51.206681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.406207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:56:51.206728Z","caller":"traceutil/trace.go:171","msg":"trace[527383193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5570; }","duration":"232.494129ms","start":"2026-09-07T10:56:50.974221Z","end":"2026-09-07T10:56:51.206715Z","steps":["trace[527383193] 'agreement among raft nodes before linearized reading' (duration: 232.416357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:51.210947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.390123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-07T10:56:51.211035Z","caller":"traceutil/trace.go:171","msg":"trace[889814735] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5574; }","duration":"159.536705ms","start":"2026-09-07T10:56:51.051477Z","end":"2026-09-07T10:56:51.211014Z","steps":["trace[889814735] 'agreement among raft nodes before linearized reading' (duration: 159.31714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:51.210953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.893808ms","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-09-07T10:56:51.2112Z","caller":"traceutil/trace.go:171","msg":"trace[1397901360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5574; }","duration":"155.182763ms","start":"2026-09-07T10:56:51.055984Z","end":"2026-09-07T10:56:51.211167Z","steps":["trace[1397901360] 'agreement among raft nodes before linearized reading' (duration: 154.854356ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:57:03.519792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-09-07T10:57:03.599129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"78.151873ms","hash":3497824285,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":31916032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T10:57:03.599218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3497824285,"revision":2960,"compact-revision":-1} {"level":"warn","ts":"2026-09-07T10:57:27.142343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.584133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-09-07T10:57:27.142445Z","caller":"traceutil/trace.go:171","msg":"trace[186874271] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5911; }","duration":"127.737405ms","start":"2026-09-07T10:57:27.014683Z","end":"2026-09-07T10:57:27.14242Z","steps":["trace[186874271] 'range keys from in-memory index tree' (duration: 127.344566ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:57:27.481916Z","caller":"traceutil/trace.go:171","msg":"trace[795024037] transaction","detail":"{read_only:false; response_revision:5913; number_of_response:1; }","duration":"135.173925ms","start":"2026-09-07T10:57:27.34669Z","end":"2026-09-07T10:57:27.481863Z","steps":["trace[795024037] 'process raft request' (duration: 134.893009ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:02:03.526943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5775} {"level":"info","ts":"2026-09-07T11:02:03.6183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5775,"took":"89.07096ms","hash":3833937102,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":25681920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T11:02:03.61838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3833937102,"revision":5775,"compact-revision":2960} {"level":"info","ts":"2026-09-07T11:04:20.572953Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002433.wal"} {"level":"info","ts":"2026-09-07T11:04:36.152612Z","caller":"traceutil/trace.go:171","msg":"trace[562763969] transaction","detail":"{read_only:false; response_revision:9032; number_of_response:1; }","duration":"124.061481ms","start":"2026-09-07T11:04:36.028512Z","end":"2026-09-07T11:04:36.152573Z","steps":["trace[562763969] 'process raft request' (duration: 123.827266ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:37.191551Z","caller":"traceutil/trace.go:171","msg":"trace[956612171] linearizableReadLoop","detail":"{readStateIndex:9361; appliedIndex:9360; }","duration":"167.278779ms","start":"2026-09-07T11:04:37.024243Z","end":"2026-09-07T11:04:37.191522Z","steps":["trace[956612171] 'read index received' (duration: 141.914795ms)","trace[956612171] 'applied index is now lower than readState.Index' (duration: 25.362593ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:37.191629Z","caller":"traceutil/trace.go:171","msg":"trace[1081274849] transaction","detail":"{read_only:false; response_revision:9038; number_of_response:1; }","duration":"238.075927ms","start":"2026-09-07T11:04:36.953507Z","end":"2026-09-07T11:04:37.191583Z","steps":["trace[1081274849] 'process raft request' (duration: 212.711103ms)","trace[1081274849] 'compare' (duration: 25.141769ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:37.191827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.567514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l4m7r\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T11:04:37.19187Z","caller":"traceutil/trace.go:171","msg":"trace[1079712564] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l4m7r; range_end:; response_count:1; response_revision:9038; }","duration":"167.675747ms","start":"2026-09-07T11:04:37.024183Z","end":"2026-09-07T11:04:37.191858Z","steps":["trace[1079712564] 'agreement among raft nodes before linearized reading' (duration: 167.458332ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:05:07.602699Z","caller":"traceutil/trace.go:171","msg":"trace[821379412] transaction","detail":"{read_only:false; response_revision:9258; number_of_response:1; }","duration":"200.642784ms","start":"2026-09-07T11:05:07.401957Z","end":"2026-09-07T11:05:07.6026Z","steps":["trace[821379412] 'process raft request' (duration: 181.486343ms)","trace[821379412] 'compare' (duration: 18.529298ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:05:07.614309Z","caller":"traceutil/trace.go:171","msg":"trace[903971995] transaction","detail":"{read_only:false; response_revision:9259; number_of_response:1; }","duration":"180.370749ms","start":"2026-09-07T11:05:07.433919Z","end":"2026-09-07T11:05:07.61429Z","steps":["trace[903971995] 'process raft request' (duration: 180.067792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:05:09.980497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.623253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:05:09.980641Z","caller":"traceutil/trace.go:171","msg":"trace[1307884826] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:9272; }","duration":"171.840267ms","start":"2026-09-07T11:05:09.808765Z","end":"2026-09-07T11:05:09.980606Z","steps":["trace[1307884826] 'count revisions from in-memory index tree' (duration: 171.488219ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:05:17.822369Z","caller":"traceutil/trace.go:171","msg":"trace[272219187] transaction","detail":"{read_only:false; response_revision:9314; number_of_response:1; }","duration":"140.916643ms","start":"2026-09-07T11:05:17.681422Z","end":"2026-09-07T11:05:17.822338Z","steps":["trace[272219187] 'process raft request' (duration: 124.551031ms)","trace[272219187] 'compare' (duration: 16.202358ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:05:18.411714Z","caller":"traceutil/trace.go:171","msg":"trace[1583960487] transaction","detail":"{read_only:false; response_revision:9316; number_of_response:1; }","duration":"195.851522ms","start":"2026-09-07T11:05:18.21584Z","end":"2026-09-07T11:05:18.411691Z","steps":["trace[1583960487] 'process raft request' (duration: 195.705129ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:05:18.412217Z","caller":"traceutil/trace.go:171","msg":"trace[106827913] linearizableReadLoop","detail":"{readStateIndex:9650; appliedIndex:9650; }","duration":"157.791335ms","start":"2026-09-07T11:05:18.254413Z","end":"2026-09-07T11:05:18.412205Z","steps":["trace[106827913] 'read index received' (duration: 157.786885ms)","trace[106827913] 'applied index is now lower than readState.Index' (duration: 3.53µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:05:18.412532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.095293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-07T11:05:18.412645Z","caller":"traceutil/trace.go:171","msg":"trace[1940500013] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9316; }","duration":"158.281616ms","start":"2026-09-07T11:05:18.254337Z","end":"2026-09-07T11:05:18.412619Z","steps":["trace[1940500013] 'agreement among raft nodes before linearized reading' (duration: 157.954008ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:05:50.931472Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:05:50.938754Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T11:05:50.939022Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T11:07:03.536311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7836} {"level":"info","ts":"2026-09-07T11:07:03.61833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7836,"took":"78.770233ms","hash":1729660822,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":27549696,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T11:07:03.618445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1729660822,"revision":7836,"compact-revision":5775} {"level":"info","ts":"2026-09-07T11:11:20.191268Z","caller":"traceutil/trace.go:171","msg":"trace[1845948915] linearizableReadLoop","detail":"{readStateIndex:12359; appliedIndex:12358; }","duration":"242.719494ms","start":"2026-09-07T11:11:19.948516Z","end":"2026-09-07T11:11:20.191236Z","steps":["trace[1845948915] 'read index received' (duration: 242.359716ms)","trace[1845948915] 'applied index is now lower than readState.Index' (duration: 358.078µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:20.191314Z","caller":"traceutil/trace.go:171","msg":"trace[1464139102] transaction","detail":"{read_only:false; response_revision:11939; number_of_response:1; }","duration":"256.700422ms","start":"2026-09-07T11:11:19.934561Z","end":"2026-09-07T11:11:20.191262Z","steps":["trace[1464139102] 'process raft request' (duration: 256.427767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:20.191466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.920658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:11:20.191517Z","caller":"traceutil/trace.go:171","msg":"trace[1110672090] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:11939; }","duration":"243.043331ms","start":"2026-09-07T11:11:19.94846Z","end":"2026-09-07T11:11:20.191503Z","steps":["trace[1110672090] 'agreement among raft nodes before linearized reading' (duration: 242.920028ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:22.410042Z","caller":"traceutil/trace.go:171","msg":"trace[611688384] transaction","detail":"{read_only:false; response_revision:11955; number_of_response:1; }","duration":"111.087173ms","start":"2026-09-07T11:11:22.298898Z","end":"2026-09-07T11:11:22.409985Z","steps":["trace[611688384] 'process raft request' (duration: 90.452033ms)","trace[611688384] 'compare' (duration: 14.211433ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:28.412365Z","caller":"traceutil/trace.go:171","msg":"trace[2018405644] transaction","detail":"{read_only:false; response_revision:11986; number_of_response:1; }","duration":"141.229767ms","start":"2026-09-07T11:11:28.271104Z","end":"2026-09-07T11:11:28.412334Z","steps":["trace[2018405644] 'process raft request' (duration: 141.053713ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:29.922648Z","caller":"traceutil/trace.go:171","msg":"trace[413861643] transaction","detail":"{read_only:false; response_revision:11997; number_of_response:1; }","duration":"102.99197ms","start":"2026-09-07T11:11:29.819628Z","end":"2026-09-07T11:11:29.92262Z","steps":["trace[413861643] 'process raft request' (duration: 102.796035ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:30.832747Z","caller":"traceutil/trace.go:171","msg":"trace[1366518367] transaction","detail":"{read_only:false; response_revision:12001; number_of_response:1; }","duration":"104.762228ms","start":"2026-09-07T11:11:30.727955Z","end":"2026-09-07T11:11:30.832717Z","steps":["trace[1366518367] 'process raft request' (duration: 104.581014ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:31.291826Z","caller":"traceutil/trace.go:171","msg":"trace[1369477810] transaction","detail":"{read_only:false; response_revision:12007; number_of_response:1; }","duration":"111.670815ms","start":"2026-09-07T11:11:31.180123Z","end":"2026-09-07T11:11:31.291794Z","steps":["trace[1369477810] 'process raft request' (duration: 111.498051ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:31.433799Z","caller":"traceutil/trace.go:171","msg":"trace[1025958063] transaction","detail":"{read_only:false; response_revision:12010; number_of_response:1; }","duration":"117.0956ms","start":"2026-09-07T11:11:31.316675Z","end":"2026-09-07T11:11:31.433771Z","steps":["trace[1025958063] 'process raft request' (duration: 61.80287ms)","trace[1025958063] 'compare' (duration: 55.102826ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:32.225247Z","caller":"traceutil/trace.go:171","msg":"trace[488448672] transaction","detail":"{read_only:false; response_revision:12014; number_of_response:1; }","duration":"158.794861ms","start":"2026-09-07T11:11:32.066427Z","end":"2026-09-07T11:11:32.225222Z","steps":["trace[488448672] 'process raft request' (duration: 158.593977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.57554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.199914Z","time spent":"375.619332ms","remote":"127.0.0.1:60656","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-07T11:11:33.576347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.997797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:11:33.576517Z","caller":"traceutil/trace.go:171","msg":"trace[1920064599] transaction","detail":"{read_only:false; response_revision:12018; number_of_response:1; }","duration":"425.363515ms","start":"2026-09-07T11:11:33.151062Z","end":"2026-09-07T11:11:33.576426Z","steps":["trace[1920064599] 'process raft request' (duration: 88.162063ms)","trace[1920064599] 'compare' (duration: 336.885855ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:11:33.576658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.151042Z","time spent":"425.523968ms","remote":"127.0.0.1:33842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7896,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:11:33.898131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.773189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:11:33.89826Z","caller":"traceutil/trace.go:171","msg":"trace[1854827880] linearizableReadLoop","detail":"{readStateIndex:12442; appliedIndex:12441; }","duration":"565.275423ms","start":"2026-09-07T11:11:33.332953Z","end":"2026-09-07T11:11:33.898229Z","steps":["trace[1854827880] 'read index received' (duration: 385.227968ms)","trace[1854827880] 'applied index is now lower than readState.Index' (duration: 180.045875ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:33.898457Z","caller":"traceutil/trace.go:171","msg":"trace[1407521246] transaction","detail":"{read_only:false; response_revision:12019; number_of_response:1; }","duration":"590.38671ms","start":"2026-09-07T11:11:33.308055Z","end":"2026-09-07T11:11:33.898441Z","steps":["trace[1407521246] 'process raft request' (duration: 410.215942ms)","trace[1407521246] 'compare' (duration: 179.615346ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:11:33.898521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.308037Z","time spent":"590.458261ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:11:33.89866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.672562ms","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-09-07T11:11:33.898766Z","caller":"traceutil/trace.go:171","msg":"trace[1367221653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12019; }","duration":"565.854475ms","start":"2026-09-07T11:11:33.332887Z","end":"2026-09-07T11:11:33.898741Z","steps":["trace[1367221653] 'agreement among raft nodes before linearized reading' (duration: 565.566489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.898818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.332867Z","time spent":"565.942666ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-07T11:11:33.977686Z","caller":"traceutil/trace.go:171","msg":"trace[62677012] transaction","detail":"{read_only:false; response_revision:12020; number_of_response:1; }","duration":"579.247891ms","start":"2026-09-07T11:11:33.398407Z","end":"2026-09-07T11:11:33.977655Z","steps":["trace[62677012] 'process raft request' (duration: 578.767611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.977869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.398379Z","time spent":"579.392004ms","remote":"127.0.0.1:50136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415725,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:11:33.978103Z","caller":"traceutil/trace.go:171","msg":"trace[1348156936] transaction","detail":"{read_only:false; response_revision:12021; number_of_response:1; }","duration":"538.952592ms","start":"2026-09-07T11:11:33.43913Z","end":"2026-09-07T11:11:33.978082Z","steps":["trace[1348156936] 'process raft request' (duration: 538.4375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.978339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.227635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T11:11:33.978401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.439091Z","time spent":"539.118445ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:11:33.978416Z","caller":"traceutil/trace.go:171","msg":"trace[1233919659] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:12022; }","duration":"479.360568ms","start":"2026-09-07T11:11:33.499037Z","end":"2026-09-07T11:11:33.978397Z","steps":["trace[1233919659] 'agreement among raft nodes before linearized reading' (duration: 479.188765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.978555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.445454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T11:11:33.9786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.499012Z","time spent":"479.541562ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":15,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:11:33.978348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.347153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T11:11:33.978664Z","caller":"traceutil/trace.go:171","msg":"trace[1459616999] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:12022; }","duration":"210.642278ms","start":"2026-09-07T11:11:33.767996Z","end":"2026-09-07T11:11:33.978638Z","steps":["trace[1459616999] 'agreement among raft nodes before linearized reading' (duration: 210.371913ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:33.978693Z","caller":"traceutil/trace.go:171","msg":"trace[1949549101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12022; }","duration":"350.726311ms","start":"2026-09-07T11:11:33.627946Z","end":"2026-09-07T11:11:33.978672Z","steps":["trace[1949549101] 'agreement among raft nodes before linearized reading' (duration: 350.159849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.978756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.627923Z","time spent":"350.816472ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T11:11:33.978763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.793764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:11:33.978897Z","caller":"traceutil/trace.go:171","msg":"trace[1515499593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12022; }","duration":"522.183123ms","start":"2026-09-07T11:11:33.456679Z","end":"2026-09-07T11:11:33.978862Z","steps":["trace[1515499593] 'agreement among raft nodes before linearized reading' (duration: 521.721173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.978958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.456656Z","time spent":"522.293056ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T11:11:33.978271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.752178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-cinder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:11:33.979025Z","caller":"traceutil/trace.go:171","msg":"trace[1507797403] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-cinder; range_end:; response_count:0; response_revision:12022; }","duration":"193.557334ms","start":"2026-09-07T11:11:33.785447Z","end":"2026-09-07T11:11:33.979004Z","steps":["trace[1507797403] 'agreement among raft nodes before linearized reading' (duration: 192.722166ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:33.978431Z","caller":"traceutil/trace.go:171","msg":"trace[1164837082] transaction","detail":"{read_only:false; response_revision:12022; number_of_response:1; }","duration":"454.15532ms","start":"2026-09-07T11:11:33.524238Z","end":"2026-09-07T11:11:33.978393Z","steps":["trace[1164837082] 'process raft request' (duration: 453.612918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.978492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.264787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:20039"} {"level":"warn","ts":"2026-09-07T11:11:33.979256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.524211Z","time spent":"454.925566ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:11:33.979325Z","caller":"traceutil/trace.go:171","msg":"trace[1421841304] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:12022; }","duration":"392.121125ms","start":"2026-09-07T11:11:33.587145Z","end":"2026-09-07T11:11:33.979266Z","steps":["trace[1421841304] 'agreement among raft nodes before linearized reading' (duration: 391.214536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:33.979394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:33.587127Z","time spent":"392.246938ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":20063,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"info","ts":"2026-09-07T11:11:34.172539Z","caller":"traceutil/trace.go:171","msg":"trace[2117735261] transaction","detail":"{read_only:false; response_revision:12023; number_of_response:1; }","duration":"269.24804ms","start":"2026-09-07T11:11:33.903258Z","end":"2026-09-07T11:11:34.172506Z","steps":["trace[2117735261] 'process raft request' (duration: 268.978535ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.172672Z","caller":"traceutil/trace.go:171","msg":"trace[115329463] linearizableReadLoop","detail":"{readStateIndex:12447; appliedIndex:12445; }","duration":"194.646626ms","start":"2026-09-07T11:11:33.978003Z","end":"2026-09-07T11:11:34.17265Z","steps":["trace[115329463] 'read index received' (duration: 194.215257ms)","trace[115329463] 'applied index is now lower than readState.Index' (duration: 430.139µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:34.172738Z","caller":"traceutil/trace.go:171","msg":"trace[97274351] transaction","detail":"{read_only:false; response_revision:12024; number_of_response:1; }","duration":"263.859545ms","start":"2026-09-07T11:11:33.908867Z","end":"2026-09-07T11:11:34.172727Z","steps":["trace[97274351] 'process raft request' (duration: 263.580699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:34.172805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.627342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:11:34.172835Z","caller":"traceutil/trace.go:171","msg":"trace[613277362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12024; }","duration":"198.719764ms","start":"2026-09-07T11:11:33.974106Z","end":"2026-09-07T11:11:34.172826Z","steps":["trace[613277362] 'agreement among raft nodes before linearized reading' (duration: 198.633042ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.190263Z","caller":"traceutil/trace.go:171","msg":"trace[1260569357] transaction","detail":"{read_only:false; response_revision:12025; number_of_response:1; }","duration":"203.832203ms","start":"2026-09-07T11:11:33.986408Z","end":"2026-09-07T11:11:34.190241Z","steps":["trace[1260569357] 'process raft request' (duration: 203.6792ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.19055Z","caller":"traceutil/trace.go:171","msg":"trace[1120977544] transaction","detail":"{read_only:false; response_revision:12026; number_of_response:1; }","duration":"202.952104ms","start":"2026-09-07T11:11:33.987577Z","end":"2026-09-07T11:11:34.190529Z","steps":["trace[1120977544] 'process raft request' (duration: 202.626147ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.190661Z","caller":"traceutil/trace.go:171","msg":"trace[1322030891] transaction","detail":"{read_only:false; response_revision:12027; number_of_response:1; }","duration":"201.509944ms","start":"2026-09-07T11:11:33.989139Z","end":"2026-09-07T11:11:34.190649Z","steps":["trace[1322030891] 'process raft request' (duration: 201.297759ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.332699Z","caller":"traceutil/trace.go:171","msg":"trace[1123035510] transaction","detail":"{read_only:false; response_revision:12028; number_of_response:1; }","duration":"125.442429ms","start":"2026-09-07T11:11:34.207193Z","end":"2026-09-07T11:11:34.332636Z","steps":["trace[1123035510] 'process raft request' (duration: 114.186819ms)","trace[1123035510] 'compare' (duration: 10.944884ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:34.650345Z","caller":"traceutil/trace.go:171","msg":"trace[1884141114] transaction","detail":"{read_only:false; response_revision:12031; number_of_response:1; }","duration":"158.119788ms","start":"2026-09-07T11:11:34.492197Z","end":"2026-09-07T11:11:34.650317Z","steps":["trace[1884141114] 'process raft request' (duration: 141.574534ms)","trace[1884141114] 'compare' (duration: 16.231146ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:34.650517Z","caller":"traceutil/trace.go:171","msg":"trace[631338291] transaction","detail":"{read_only:false; response_revision:12033; number_of_response:1; }","duration":"156.169495ms","start":"2026-09-07T11:11:34.494323Z","end":"2026-09-07T11:11:34.650492Z","steps":["trace[631338291] 'process raft request' (duration: 155.989982ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.650563Z","caller":"traceutil/trace.go:171","msg":"trace[692258634] transaction","detail":"{read_only:false; response_revision:12032; number_of_response:1; }","duration":"158.142168ms","start":"2026-09-07T11:11:34.492371Z","end":"2026-09-07T11:11:34.650513Z","steps":["trace[692258634] 'process raft request' (duration: 157.83088ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.651114Z","caller":"traceutil/trace.go:171","msg":"trace[767414797] linearizableReadLoop","detail":"{readStateIndex:12458; appliedIndex:12454; }","duration":"111.927851ms","start":"2026-09-07T11:11:34.539171Z","end":"2026-09-07T11:11:34.651099Z","steps":["trace[767414797] 'read index received' (duration: 94.595791ms)","trace[767414797] 'applied index is now lower than readState.Index' (duration: 17.33102ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:34.651353Z","caller":"traceutil/trace.go:171","msg":"trace[388651791] transaction","detail":"{read_only:false; response_revision:12034; number_of_response:1; }","duration":"129.287301ms","start":"2026-09-07T11:11:34.522049Z","end":"2026-09-07T11:11:34.651336Z","steps":["trace[388651791] 'process raft request' (duration: 128.932874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:34.651397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.180246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T11:11:34.651492Z","caller":"traceutil/trace.go:171","msg":"trace[342325197] transaction","detail":"{read_only:false; response_revision:12035; number_of_response:1; }","duration":"107.605178ms","start":"2026-09-07T11:11:34.543865Z","end":"2026-09-07T11:11:34.65147Z","steps":["trace[342325197] 'process raft request' (duration: 107.182869ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.651449Z","caller":"traceutil/trace.go:171","msg":"trace[1143923078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12035; }","duration":"112.272918ms","start":"2026-09-07T11:11:34.539165Z","end":"2026-09-07T11:11:34.651438Z","steps":["trace[1143923078] 'agreement among raft nodes before linearized reading' (duration: 111.986322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:34.949791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.778386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:11:34.949875Z","caller":"traceutil/trace.go:171","msg":"trace[428014577] linearizableReadLoop","detail":"{readStateIndex:12461; appliedIndex:12460; }","duration":"257.249143ms","start":"2026-09-07T11:11:34.692613Z","end":"2026-09-07T11:11:34.949862Z","steps":["trace[428014577] 'read index received' (duration: 116.364635ms)","trace[428014577] 'applied index is now lower than readState.Index' (duration: 140.883598ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:34.950233Z","caller":"traceutil/trace.go:171","msg":"trace[1393155071] transaction","detail":"{read_only:false; response_revision:12037; number_of_response:1; }","duration":"293.323285ms","start":"2026-09-07T11:11:34.656872Z","end":"2026-09-07T11:11:34.950195Z","steps":["trace[1393155071] 'process raft request' (duration: 152.065508ms)","trace[1393155071] 'compare' (duration: 140.693645ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:11:34.95035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.797376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l4m7r\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T11:11:34.950817Z","caller":"traceutil/trace.go:171","msg":"trace[1881430050] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l4m7r; range_end:; response_count:1; response_revision:12037; }","duration":"258.261905ms","start":"2026-09-07T11:11:34.692543Z","end":"2026-09-07T11:11:34.950805Z","steps":["trace[1881430050] 'agreement among raft nodes before linearized reading' (duration: 257.681643ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.953039Z","caller":"traceutil/trace.go:171","msg":"trace[2005396282] transaction","detail":"{read_only:false; response_revision:12038; number_of_response:1; }","duration":"258.981841ms","start":"2026-09-07T11:11:34.694028Z","end":"2026-09-07T11:11:34.95301Z","steps":["trace[2005396282] 'process raft request' (duration: 258.850639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:34.953143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.120736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-api-689dd95b5d-6nlmx\" ","response":"range_response_count:1 size:7649"} {"level":"info","ts":"2026-09-07T11:11:34.953227Z","caller":"traceutil/trace.go:171","msg":"trace[1734581588] transaction","detail":"{read_only:false; response_revision:12042; number_of_response:1; }","duration":"193.621136ms","start":"2026-09-07T11:11:34.759582Z","end":"2026-09-07T11:11:34.953203Z","steps":["trace[1734581588] 'process raft request' (duration: 193.456102ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.953198Z","caller":"traceutil/trace.go:171","msg":"trace[1562484441] transaction","detail":"{read_only:false; response_revision:12040; number_of_response:1; }","duration":"251.92023ms","start":"2026-09-07T11:11:34.701234Z","end":"2026-09-07T11:11:34.953154Z","steps":["trace[1562484441] 'process raft request' (duration: 251.746277ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.953241Z","caller":"traceutil/trace.go:171","msg":"trace[702840094] transaction","detail":"{read_only:false; response_revision:12039; number_of_response:1; }","duration":"259.138035ms","start":"2026-09-07T11:11:34.694075Z","end":"2026-09-07T11:11:34.953213Z","steps":["trace[702840094] 'process raft request' (duration: 258.869619ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.95336Z","caller":"traceutil/trace.go:171","msg":"trace[1307406793] range","detail":"{range_begin:/registry/pods/openstack/staffeln-api-689dd95b5d-6nlmx; range_end:; response_count:1; response_revision:12042; }","duration":"194.395903ms","start":"2026-09-07T11:11:34.758952Z","end":"2026-09-07T11:11:34.953348Z","steps":["trace[1307406793] 'agreement among raft nodes before linearized reading' (duration: 194.119207ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:34.953807Z","caller":"traceutil/trace.go:171","msg":"trace[1553151383] transaction","detail":"{read_only:false; response_revision:12041; number_of_response:1; }","duration":"251.912511ms","start":"2026-09-07T11:11:34.701245Z","end":"2026-09-07T11:11:34.953157Z","steps":["trace[1553151383] 'process raft request' (duration: 251.762767ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:35.153775Z","caller":"traceutil/trace.go:171","msg":"trace[1511485811] linearizableReadLoop","detail":"{readStateIndex:12468; appliedIndex:12467; }","duration":"192.089822ms","start":"2026-09-07T11:11:34.961672Z","end":"2026-09-07T11:11:35.153762Z","steps":["trace[1511485811] 'read index received' (duration: 16.592035ms)","trace[1511485811] 'applied index is now lower than readState.Index' (duration: 175.497077ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:35.153862Z","caller":"traceutil/trace.go:171","msg":"trace[1033261783] transaction","detail":"{read_only:false; response_revision:12045; number_of_response:1; }","duration":"189.089578ms","start":"2026-09-07T11:11:34.964764Z","end":"2026-09-07T11:11:35.153854Z","steps":["trace[1033261783] 'process raft request' (duration: 188.942965ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:35.153915Z","caller":"traceutil/trace.go:171","msg":"trace[829278245] transaction","detail":"{read_only:false; response_revision:12044; number_of_response:1; }","duration":"193.402211ms","start":"2026-09-07T11:11:34.96049Z","end":"2026-09-07T11:11:35.153893Z","steps":["trace[829278245] 'process raft request' (duration: 193.111845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.154364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.676865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-09-07T11:11:35.154414Z","caller":"traceutil/trace.go:171","msg":"trace[62560779] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:12045; }","duration":"192.783417ms","start":"2026-09-07T11:11:34.961617Z","end":"2026-09-07T11:11:35.154401Z","steps":["trace[62560779] 'agreement among raft nodes before linearized reading' (duration: 192.605773ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:35.42664Z","caller":"traceutil/trace.go:171","msg":"trace[855182407] linearizableReadLoop","detail":"{readStateIndex:12470; appliedIndex:12469; }","duration":"272.773046ms","start":"2026-09-07T11:11:35.153832Z","end":"2026-09-07T11:11:35.426605Z","steps":["trace[855182407] 'read index received' (duration: 272.374177ms)","trace[855182407] 'applied index is now lower than readState.Index' (duration: 397.229µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:35.426805Z","caller":"traceutil/trace.go:171","msg":"trace[1720158710] transaction","detail":"{read_only:false; response_revision:12046; number_of_response:1; }","duration":"443.510003ms","start":"2026-09-07T11:11:34.983141Z","end":"2026-09-07T11:11:35.426651Z","steps":["trace[1720158710] 'process raft request' (duration: 443.209887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.426985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:34.983118Z","time spent":"443.785989ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":824,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T11:11:35.427022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.63813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20039"} {"level":"info","ts":"2026-09-07T11:11:35.427063Z","caller":"traceutil/trace.go:171","msg":"trace[1418149548] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12046; }","duration":"277.744512ms","start":"2026-09-07T11:11:35.149306Z","end":"2026-09-07T11:11:35.42705Z","steps":["trace[1418149548] 'agreement among raft nodes before linearized reading' (duration: 277.459946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.427086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.540768ms","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-09-07T11:11:35.427152Z","caller":"traceutil/trace.go:171","msg":"trace[174811316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12046; }","duration":"277.637009ms","start":"2026-09-07T11:11:35.149495Z","end":"2026-09-07T11:11:35.427132Z","steps":["trace[174811316] 'agreement among raft nodes before linearized reading' (duration: 277.19444ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:35.453183Z","caller":"traceutil/trace.go:171","msg":"trace[89021453] transaction","detail":"{read_only:false; response_revision:12047; number_of_response:1; }","duration":"290.064595ms","start":"2026-09-07T11:11:35.163088Z","end":"2026-09-07T11:11:35.453153Z","steps":["trace[89021453] 'process raft request' (duration: 289.718197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.453551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.793978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-07T11:11:35.453682Z","caller":"traceutil/trace.go:171","msg":"trace[518293708] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:12047; }","duration":"281.977782ms","start":"2026-09-07T11:11:35.171672Z","end":"2026-09-07T11:11:35.45365Z","steps":["trace[518293708] 'agreement among raft nodes before linearized reading' (duration: 281.586464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.45469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.092325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-09-07T11:11:35.454779Z","caller":"traceutil/trace.go:171","msg":"trace[802518219] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:12047; }","duration":"157.219037ms","start":"2026-09-07T11:11:35.297536Z","end":"2026-09-07T11:11:35.454755Z","steps":["trace[802518219] 'agreement among raft nodes before linearized reading' (duration: 155.554261ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:35.589265Z","caller":"traceutil/trace.go:171","msg":"trace[575490494] linearizableReadLoop","detail":"{readStateIndex:12474; appliedIndex:12473; }","duration":"124.593942ms","start":"2026-09-07T11:11:35.464642Z","end":"2026-09-07T11:11:35.589236Z","steps":["trace[575490494] 'read index received' (duration: 124.458899ms)","trace[575490494] 'applied index is now lower than readState.Index' (duration: 133.593µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:35.58939Z","caller":"traceutil/trace.go:171","msg":"trace[2092250630] transaction","detail":"{read_only:false; response_revision:12050; number_of_response:1; }","duration":"130.771814ms","start":"2026-09-07T11:11:35.458562Z","end":"2026-09-07T11:11:35.589334Z","steps":["trace[2092250630] 'process raft request' (duration: 130.474637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.589615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.031901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-07T11:11:35.589711Z","caller":"traceutil/trace.go:171","msg":"trace[1557778933] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:12050; }","duration":"125.191634ms","start":"2026-09-07T11:11:35.464494Z","end":"2026-09-07T11:11:35.589686Z","steps":["trace[1557778933] 'agreement among raft nodes before linearized reading' (duration: 124.884458ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:35.771829Z","caller":"traceutil/trace.go:171","msg":"trace[910206341] transaction","detail":"{read_only:false; response_revision:12051; number_of_response:1; }","duration":"301.212744ms","start":"2026-09-07T11:11:35.470591Z","end":"2026-09-07T11:11:35.771804Z","steps":["trace[910206341] 'process raft request' (duration: 204.566519ms)","trace[910206341] 'compare' (duration: 96.41904ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:11:35.771956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:11:35.470564Z","time spent":"301.340007ms","remote":"127.0.0.1:38564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":690,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T11:11:35.771947Z","caller":"traceutil/trace.go:171","msg":"trace[237468851] linearizableReadLoop","detail":"{readStateIndex:12475; appliedIndex:12474; }","duration":"182.556259ms","start":"2026-09-07T11:11:35.589365Z","end":"2026-09-07T11:11:35.771921Z","steps":["trace[237468851] 'read index received' (duration: 85.809473ms)","trace[237468851] 'applied index is now lower than readState.Index' (duration: 96.744626ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:35.772021Z","caller":"traceutil/trace.go:171","msg":"trace[451807533] transaction","detail":"{read_only:false; response_revision:12052; number_of_response:1; }","duration":"177.133323ms","start":"2026-09-07T11:11:35.594847Z","end":"2026-09-07T11:11:35.77198Z","steps":["trace[451807533] 'process raft request' (duration: 176.923809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.772238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.642477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-07T11:11:35.772314Z","caller":"traceutil/trace.go:171","msg":"trace[124632387] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:12054; }","duration":"267.75545ms","start":"2026-09-07T11:11:35.504513Z","end":"2026-09-07T11:11:35.772268Z","steps":["trace[124632387] 'agreement among raft nodes before linearized reading' (duration: 267.546085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.772406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.208307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-07T11:11:35.77247Z","caller":"traceutil/trace.go:171","msg":"trace[201174283] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:12054; }","duration":"178.327089ms","start":"2026-09-07T11:11:35.594122Z","end":"2026-09-07T11:11:35.772449Z","steps":["trace[201174283] 'agreement among raft nodes before linearized reading' (duration: 178.103765ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:35.950378Z","caller":"traceutil/trace.go:171","msg":"trace[479969532] linearizableReadLoop","detail":"{readStateIndex:12480; appliedIndex:12479; }","duration":"102.817936ms","start":"2026-09-07T11:11:35.847533Z","end":"2026-09-07T11:11:35.950351Z","steps":["trace[479969532] 'read index received' (duration: 36.486599ms)","trace[479969532] 'applied index is now lower than readState.Index' (duration: 66.329847ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:35.95042Z","caller":"traceutil/trace.go:171","msg":"trace[309169523] transaction","detail":"{read_only:false; response_revision:12056; number_of_response:1; }","duration":"104.294218ms","start":"2026-09-07T11:11:35.846094Z","end":"2026-09-07T11:11:35.950388Z","steps":["trace[309169523] 'process raft request' (duration: 38.002112ms)","trace[309169523] 'compare' (duration: 66.014239ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:11:35.950616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.074321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-07T11:11:35.950654Z","caller":"traceutil/trace.go:171","msg":"trace[1978557974] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:12056; }","duration":"103.146613ms","start":"2026-09-07T11:11:35.847495Z","end":"2026-09-07T11:11:35.950641Z","steps":["trace[1978557974] 'agreement among raft nodes before linearized reading' (duration: 102.963819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:35.95067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.119682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-07T11:11:35.950751Z","caller":"traceutil/trace.go:171","msg":"trace[650680879] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:12056; }","duration":"103.240685ms","start":"2026-09-07T11:11:35.847487Z","end":"2026-09-07T11:11:35.950728Z","steps":["trace[650680879] 'agreement among raft nodes before linearized reading' (duration: 102.961679ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.105564Z","caller":"traceutil/trace.go:171","msg":"trace[1296311600] transaction","detail":"{read_only:false; response_revision:12059; number_of_response:1; }","duration":"146.369385ms","start":"2026-09-07T11:11:35.95917Z","end":"2026-09-07T11:11:36.10554Z","steps":["trace[1296311600] 'process raft request' (duration: 129.24785ms)","trace[1296311600] 'compare' (duration: 17.011703ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:36.105962Z","caller":"traceutil/trace.go:171","msg":"trace[685353983] transaction","detail":"{read_only:false; response_revision:12060; number_of_response:1; }","duration":"120.063064ms","start":"2026-09-07T11:11:35.985858Z","end":"2026-09-07T11:11:36.105921Z","steps":["trace[685353983] 'process raft request' (duration: 119.784479ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.106078Z","caller":"traceutil/trace.go:171","msg":"trace[1024550717] linearizableReadLoop","detail":"{readStateIndex:12485; appliedIndex:12482; }","duration":"108.869655ms","start":"2026-09-07T11:11:35.9972Z","end":"2026-09-07T11:11:36.106069Z","steps":["trace[1024550717] 'read index received' (duration: 91.173518ms)","trace[1024550717] 'applied index is now lower than readState.Index' (duration: 17.695417ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:11:36.106187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.693139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-07T11:11:36.106208Z","caller":"traceutil/trace.go:171","msg":"trace[1503059580] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:12061; }","duration":"150.7551ms","start":"2026-09-07T11:11:35.955446Z","end":"2026-09-07T11:11:36.106202Z","steps":["trace[1503059580] 'agreement among raft nodes before linearized reading' (duration: 150.655728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.10621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.620352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:11:36.106335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.117577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-07T11:11:36.106338Z","caller":"traceutil/trace.go:171","msg":"trace[606592282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12061; }","duration":"132.785236ms","start":"2026-09-07T11:11:35.97353Z","end":"2026-09-07T11:11:36.106315Z","steps":["trace[606592282] 'agreement among raft nodes before linearized reading' (duration: 132.616872ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.106358Z","caller":"traceutil/trace.go:171","msg":"trace[249704759] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:12061; }","duration":"143.155537ms","start":"2026-09-07T11:11:35.963194Z","end":"2026-09-07T11:11:36.106349Z","steps":["trace[249704759] 'agreement among raft nodes before linearized reading' (duration: 143.100746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.106461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.646976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cinder-peer-discovery\" ","response":"range_response_count:1 size:806"} {"level":"info","ts":"2026-09-07T11:11:36.106477Z","caller":"traceutil/trace.go:171","msg":"trace[940163340] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cinder-peer-discovery; range_end:; response_count:1; response_revision:12061; }","duration":"149.664586ms","start":"2026-09-07T11:11:35.956808Z","end":"2026-09-07T11:11:36.106473Z","steps":["trace[940163340] 'agreement among raft nodes before linearized reading' (duration: 149.614755ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.10654Z","caller":"traceutil/trace.go:171","msg":"trace[1586752429] transaction","detail":"{read_only:false; response_revision:12061; number_of_response:1; }","duration":"119.577213ms","start":"2026-09-07T11:11:35.98695Z","end":"2026-09-07T11:11:36.106528Z","steps":["trace[1586752429] 'process raft request' (duration: 118.903509ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.402011Z","caller":"traceutil/trace.go:171","msg":"trace[1912283464] transaction","detail":"{read_only:false; response_revision:12065; number_of_response:1; }","duration":"195.469605ms","start":"2026-09-07T11:11:36.206515Z","end":"2026-09-07T11:11:36.401984Z","steps":["trace[1912283464] 'process raft request' (duration: 186.110104ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.402149Z","caller":"traceutil/trace.go:171","msg":"trace[1391548272] linearizableReadLoop","detail":"{readStateIndex:12490; appliedIndex:12487; }","duration":"193.966853ms","start":"2026-09-07T11:11:36.208162Z","end":"2026-09-07T11:11:36.402128Z","steps":["trace[1391548272] 'read index received' (duration: 81.188064ms)","trace[1391548272] 'applied index is now lower than readState.Index' (duration: 112.777849ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:36.402206Z","caller":"traceutil/trace.go:171","msg":"trace[1241917859] transaction","detail":"{read_only:false; response_revision:12066; number_of_response:1; }","duration":"195.384213ms","start":"2026-09-07T11:11:36.206783Z","end":"2026-09-07T11:11:36.402167Z","steps":["trace[1241917859] 'process raft request' (duration: 194.872263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.402416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.233828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-07T11:11:36.402486Z","caller":"traceutil/trace.go:171","msg":"trace[926162607] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:12068; }","duration":"194.348011ms","start":"2026-09-07T11:11:36.208117Z","end":"2026-09-07T11:11:36.402465Z","steps":["trace[926162607] 'agreement among raft nodes before linearized reading' (duration: 194.085255ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.402564Z","caller":"traceutil/trace.go:171","msg":"trace[69136844] transaction","detail":"{read_only:false; response_revision:12068; number_of_response:1; }","duration":"178.720427ms","start":"2026-09-07T11:11:36.223826Z","end":"2026-09-07T11:11:36.402547Z","steps":["trace[69136844] 'process raft request' (duration: 178.224507ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.402709Z","caller":"traceutil/trace.go:171","msg":"trace[1267203118] transaction","detail":"{read_only:false; response_revision:12067; number_of_response:1; }","duration":"193.939522ms","start":"2026-09-07T11:11:36.208756Z","end":"2026-09-07T11:11:36.402695Z","steps":["trace[1267203118] 'process raft request' (duration: 192.949281ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.539057Z","caller":"traceutil/trace.go:171","msg":"trace[225526855] linearizableReadLoop","detail":"{readStateIndex:12494; appliedIndex:12492; }","duration":"126.4076ms","start":"2026-09-07T11:11:36.412626Z","end":"2026-09-07T11:11:36.539034Z","steps":["trace[225526855] 'read index received' (duration: 15.572722ms)","trace[225526855] 'applied index is now lower than readState.Index' (duration: 110.833808ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:11:36.539244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.597884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:11:36.539241Z","caller":"traceutil/trace.go:171","msg":"trace[343218942] transaction","detail":"{read_only:false; response_revision:12070; number_of_response:1; }","duration":"129.755802ms","start":"2026-09-07T11:11:36.409408Z","end":"2026-09-07T11:11:36.539164Z","steps":["trace[343218942] 'process raft request' (duration: 118.059592ms)","trace[343218942] 'compare' (duration: 11.168188ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:36.539307Z","caller":"traceutil/trace.go:171","msg":"trace[1852521086] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:12071; }","duration":"126.681756ms","start":"2026-09-07T11:11:36.412584Z","end":"2026-09-07T11:11:36.539266Z","steps":["trace[1852521086] 'agreement among raft nodes before linearized reading' (duration: 126.565093ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.539305Z","caller":"traceutil/trace.go:171","msg":"trace[800282661] transaction","detail":"{read_only:false; response_revision:12071; number_of_response:1; }","duration":"114.73924ms","start":"2026-09-07T11:11:36.424517Z","end":"2026-09-07T11:11:36.539257Z","steps":["trace[800282661] 'process raft request' (duration: 114.438174ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.712698Z","caller":"traceutil/trace.go:171","msg":"trace[1232281905] transaction","detail":"{read_only:false; response_revision:12074; number_of_response:1; }","duration":"122.459796ms","start":"2026-09-07T11:11:36.590193Z","end":"2026-09-07T11:11:36.712653Z","steps":["trace[1232281905] 'process raft request' (duration: 122.118419ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.712738Z","caller":"traceutil/trace.go:171","msg":"trace[223581431] transaction","detail":"{read_only:false; response_revision:12075; number_of_response:1; }","duration":"121.004994ms","start":"2026-09-07T11:11:36.591708Z","end":"2026-09-07T11:11:36.712713Z","steps":["trace[223581431] 'process raft request' (duration: 120.7966ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.712708Z","caller":"traceutil/trace.go:171","msg":"trace[714033028] transaction","detail":"{read_only:false; response_revision:12076; number_of_response:1; }","duration":"120.148977ms","start":"2026-09-07T11:11:36.592534Z","end":"2026-09-07T11:11:36.712683Z","steps":["trace[714033028] 'process raft request' (duration: 120.033624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.713869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.133756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-09-07T11:11:36.713937Z","caller":"traceutil/trace.go:171","msg":"trace[1682362568] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:12076; }","duration":"150.246139ms","start":"2026-09-07T11:11:36.563675Z","end":"2026-09-07T11:11:36.713922Z","steps":["trace[1682362568] 'agreement among raft nodes before linearized reading' (duration: 149.073524ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.881434Z","caller":"traceutil/trace.go:171","msg":"trace[564614979] linearizableReadLoop","detail":"{readStateIndex:12503; appliedIndex:12501; }","duration":"141.220916ms","start":"2026-09-07T11:11:36.740182Z","end":"2026-09-07T11:11:36.881403Z","steps":["trace[564614979] 'read index received' (duration: 140.854138ms)","trace[564614979] 'applied index is now lower than readState.Index' (duration: 365.288µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:36.881782Z","caller":"traceutil/trace.go:171","msg":"trace[450475081] transaction","detail":"{read_only:false; response_revision:12078; number_of_response:1; }","duration":"159.463376ms","start":"2026-09-07T11:11:36.722305Z","end":"2026-09-07T11:11:36.881768Z","steps":["trace[450475081] 'process raft request' (duration: 158.758131ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.882104Z","caller":"traceutil/trace.go:171","msg":"trace[1310355520] transaction","detail":"{read_only:false; response_revision:12079; number_of_response:1; }","duration":"158.853053ms","start":"2026-09-07T11:11:36.723241Z","end":"2026-09-07T11:11:36.882094Z","steps":["trace[1310355520] 'process raft request' (duration: 158.088007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.8824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.735734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-cinder-server\" ","response":"range_response_count:1 size:9284"} {"level":"info","ts":"2026-09-07T11:11:36.882443Z","caller":"traceutil/trace.go:171","msg":"trace[1503823853] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-cinder-server; range_end:; response_count:1; response_revision:12079; }","duration":"153.859227ms","start":"2026-09-07T11:11:36.728573Z","end":"2026-09-07T11:11:36.882432Z","steps":["trace[1503823853] 'agreement among raft nodes before linearized reading' (duration: 153.660992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.882683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.321163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:11:36.882724Z","caller":"traceutil/trace.go:171","msg":"trace[1241138149] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12079; }","duration":"160.446066ms","start":"2026-09-07T11:11:36.722266Z","end":"2026-09-07T11:11:36.882712Z","steps":["trace[1241138149] 'agreement among raft nodes before linearized reading' (duration: 160.369495ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:36.971807Z","caller":"traceutil/trace.go:171","msg":"trace[1725497374] transaction","detail":"{read_only:false; response_revision:12080; number_of_response:1; }","duration":"215.414191ms","start":"2026-09-07T11:11:36.756361Z","end":"2026-09-07T11:11:36.971775Z","steps":["trace[1725497374] 'process raft request' (duration: 215.257897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.97223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.468549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-api-689dd95b5d-6nlmx\" ","response":"range_response_count:1 size:7660"} {"level":"info","ts":"2026-09-07T11:11:36.972325Z","caller":"traceutil/trace.go:171","msg":"trace[1927237571] range","detail":"{range_begin:/registry/pods/openstack/staffeln-api-689dd95b5d-6nlmx; range_end:; response_count:1; response_revision:12080; }","duration":"177.621903ms","start":"2026-09-07T11:11:36.794689Z","end":"2026-09-07T11:11:36.972311Z","steps":["trace[1927237571] 'agreement among raft nodes before linearized reading' (duration: 177.357357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.972575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.10177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-07T11:11:36.972631Z","caller":"traceutil/trace.go:171","msg":"trace[519302348] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:12080; }","duration":"227.194412ms","start":"2026-09-07T11:11:36.745421Z","end":"2026-09-07T11:11:36.972616Z","steps":["trace[519302348] 'agreement among raft nodes before linearized reading' (duration: 227.034279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:36.972864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.142086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-07T11:11:36.972924Z","caller":"traceutil/trace.go:171","msg":"trace[1567135053] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:12080; }","duration":"201.240168ms","start":"2026-09-07T11:11:36.771665Z","end":"2026-09-07T11:11:36.972906Z","steps":["trace[1567135053] 'agreement among raft nodes before linearized reading' (duration: 201.095185ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.066502Z","caller":"traceutil/trace.go:171","msg":"trace[924434126] transaction","detail":"{read_only:false; response_revision:12081; number_of_response:1; }","duration":"178.107384ms","start":"2026-09-07T11:11:36.888354Z","end":"2026-09-07T11:11:37.066462Z","steps":["trace[924434126] 'process raft request' (duration: 165.945675ms)","trace[924434126] 'compare' (duration: 11.907224ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:37.066551Z","caller":"traceutil/trace.go:171","msg":"trace[1315378979] transaction","detail":"{read_only:false; response_revision:12082; number_of_response:1; }","duration":"161.906888ms","start":"2026-09-07T11:11:36.904616Z","end":"2026-09-07T11:11:37.066523Z","steps":["trace[1315378979] 'process raft request' (duration: 161.776095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.066827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.818818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-07T11:11:37.066827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.020111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.166\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-09-07T11:11:37.066867Z","caller":"traceutil/trace.go:171","msg":"trace[557370148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12082; }","duration":"111.93086ms","start":"2026-09-07T11:11:36.954925Z","end":"2026-09-07T11:11:37.066856Z","steps":["trace[557370148] 'agreement among raft nodes before linearized reading' (duration: 111.763647ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.066881Z","caller":"traceutil/trace.go:171","msg":"trace[1090551377] range","detail":"{range_begin:/registry/masterleases/162.253.55.166; range_end:; response_count:1; response_revision:12082; }","duration":"148.125704ms","start":"2026-09-07T11:11:36.918743Z","end":"2026-09-07T11:11:37.066869Z","steps":["trace[1090551377] 'agreement among raft nodes before linearized reading' (duration: 147.94853ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.086272Z","caller":"traceutil/trace.go:171","msg":"trace[1953740755] transaction","detail":"{read_only:false; response_revision:12083; number_of_response:1; }","duration":"101.870526ms","start":"2026-09-07T11:11:36.984378Z","end":"2026-09-07T11:11:37.086248Z","steps":["trace[1953740755] 'process raft request' (duration: 101.702222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.086565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.899043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l4m7r\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T11:11:37.086635Z","caller":"traceutil/trace.go:171","msg":"trace[310555764] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l4m7r; range_end:; response_count:1; response_revision:12083; }","duration":"107.015585ms","start":"2026-09-07T11:11:36.979605Z","end":"2026-09-07T11:11:37.08662Z","steps":["trace[310555764] 'agreement among raft nodes before linearized reading' (duration: 106.849392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.086664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.117407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-cinder-default-user\" ","response":"range_response_count:1 size:1309"} {"level":"info","ts":"2026-09-07T11:11:37.086703Z","caller":"traceutil/trace.go:171","msg":"trace[1529608947] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-cinder-default-user; range_end:; response_count:1; response_revision:12083; }","duration":"107.192759ms","start":"2026-09-07T11:11:36.9795Z","end":"2026-09-07T11:11:37.086693Z","steps":["trace[1529608947] 'agreement among raft nodes before linearized reading' (duration: 107.055496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.086854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.86215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:11:37.086886Z","caller":"traceutil/trace.go:171","msg":"trace[808547715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12083; }","duration":"112.920491ms","start":"2026-09-07T11:11:36.973958Z","end":"2026-09-07T11:11:37.086878Z","steps":["trace[808547715] 'agreement among raft nodes before linearized reading' (duration: 112.8712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.202038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.062168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112193"} {"level":"info","ts":"2026-09-07T11:11:37.202123Z","caller":"traceutil/trace.go:171","msg":"trace[1090622481] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:12086; }","duration":"108.17203ms","start":"2026-09-07T11:11:37.09393Z","end":"2026-09-07T11:11:37.202102Z","steps":["trace[1090622481] 'agreement among raft nodes before linearized reading' (duration: 107.850313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.20223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.247714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-09-07T11:11:37.202249Z","caller":"traceutil/trace.go:171","msg":"trace[472004117] transaction","detail":"{read_only:false; response_revision:12086; number_of_response:1; }","duration":"111.071372ms","start":"2026-09-07T11:11:37.091154Z","end":"2026-09-07T11:11:37.202225Z","steps":["trace[472004117] 'process raft request' (duration: 101.548249ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.202061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.861194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-cinder-nodes\" ","response":"range_response_count:1 size:1255"} {"level":"info","ts":"2026-09-07T11:11:37.202326Z","caller":"traceutil/trace.go:171","msg":"trace[578025145] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:12086; }","duration":"109.348145ms","start":"2026-09-07T11:11:37.092924Z","end":"2026-09-07T11:11:37.202272Z","steps":["trace[578025145] 'agreement among raft nodes before linearized reading' (duration: 109.194132ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.202348Z","caller":"traceutil/trace.go:171","msg":"trace[1149006852] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-cinder-nodes; range_end:; response_count:1; response_revision:12086; }","duration":"109.14758ms","start":"2026-09-07T11:11:37.09318Z","end":"2026-09-07T11:11:37.202327Z","steps":["trace[1149006852] 'agreement among raft nodes before linearized reading' (duration: 108.765792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.202985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.915755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:42 size:384628"} {"level":"info","ts":"2026-09-07T11:11:37.203078Z","caller":"traceutil/trace.go:171","msg":"trace[1164273535] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:42; response_revision:12086; }","duration":"108.039378ms","start":"2026-09-07T11:11:37.095018Z","end":"2026-09-07T11:11:37.203057Z","steps":["trace[1164273535] 'agreement among raft nodes before linearized reading' (duration: 107.2442ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.255259Z","caller":"traceutil/trace.go:171","msg":"trace[1424111149] transaction","detail":"{read_only:false; response_revision:12088; number_of_response:1; }","duration":"106.458933ms","start":"2026-09-07T11:11:37.148768Z","end":"2026-09-07T11:11:37.255227Z","steps":["trace[1424111149] 'process raft request' (duration: 106.332961ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.255324Z","caller":"traceutil/trace.go:171","msg":"trace[1384582898] transaction","detail":"{read_only:false; response_revision:12087; number_of_response:1; }","duration":"112.178015ms","start":"2026-09-07T11:11:37.143094Z","end":"2026-09-07T11:11:37.255272Z","steps":["trace[1384582898] 'process raft request' (duration: 111.850938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.255545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.516685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:11:37.255624Z","caller":"traceutil/trace.go:171","msg":"trace[613653324] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:12088; }","duration":"106.644588ms","start":"2026-09-07T11:11:37.148958Z","end":"2026-09-07T11:11:37.255602Z","steps":["trace[613653324] 'agreement among raft nodes before linearized reading' (duration: 106.447633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.25574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.311924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-cinder-server-0.18d30579a8474612\" ","response":"range_response_count:1 size:1007"} {"level":"info","ts":"2026-09-07T11:11:37.255796Z","caller":"traceutil/trace.go:171","msg":"trace[225717753] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-cinder-server-0.18d30579a8474612; range_end:; response_count:1; response_revision:12088; }","duration":"108.414016ms","start":"2026-09-07T11:11:37.147367Z","end":"2026-09-07T11:11:37.255781Z","steps":["trace[225717753] 'agreement among raft nodes before linearized reading' (duration: 108.18055ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.504959Z","caller":"traceutil/trace.go:171","msg":"trace[1790169217] linearizableReadLoop","detail":"{readStateIndex:12519; appliedIndex:12518; }","duration":"113.221399ms","start":"2026-09-07T11:11:37.391704Z","end":"2026-09-07T11:11:37.504926Z","steps":["trace[1790169217] 'read index received' (duration: 69.100447ms)","trace[1790169217] 'applied index is now lower than readState.Index' (duration: 44.119552ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:37.504978Z","caller":"traceutil/trace.go:171","msg":"trace[574422371] transaction","detail":"{read_only:false; response_revision:12094; number_of_response:1; }","duration":"125.459949ms","start":"2026-09-07T11:11:37.379474Z","end":"2026-09-07T11:11:37.504934Z","steps":["trace[574422371] 'process raft request' (duration: 81.297186ms)","trace[574422371] 'compare' (duration: 43.951338ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:11:37.505247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.842317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-cinder\" ","response":"range_response_count:1 size:3306"} {"level":"warn","ts":"2026-09-07T11:11:37.505256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.716333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-09-07T11:11:37.505329Z","caller":"traceutil/trace.go:171","msg":"trace[1927046356] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-cinder; range_end:; response_count:1; response_revision:12094; }","duration":"125.96408ms","start":"2026-09-07T11:11:37.379352Z","end":"2026-09-07T11:11:37.505317Z","steps":["trace[1927046356] 'agreement among raft nodes before linearized reading' (duration: 125.707465ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.505355Z","caller":"traceutil/trace.go:171","msg":"trace[573334704] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:12094; }","duration":"108.847155ms","start":"2026-09-07T11:11:37.39649Z","end":"2026-09-07T11:11:37.505337Z","steps":["trace[573334704] 'agreement among raft nodes before linearized reading' (duration: 108.63155ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:37.650119Z","caller":"traceutil/trace.go:171","msg":"trace[1591423704] transaction","detail":"{read_only:false; response_revision:12096; number_of_response:1; }","duration":"101.630731ms","start":"2026-09-07T11:11:37.548454Z","end":"2026-09-07T11:11:37.650085Z","steps":["trace[1591423704] 'process raft request' (duration: 60.088584ms)","trace[1591423704] 'compare' (duration: 41.387253ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:37.69737Z","caller":"traceutil/trace.go:171","msg":"trace[1605712607] linearizableReadLoop","detail":"{readStateIndex:12522; appliedIndex:12520; }","duration":"146.486938ms","start":"2026-09-07T11:11:37.550859Z","end":"2026-09-07T11:11:37.697346Z","steps":["trace[1605712607] 'read index received' (duration: 57.695382ms)","trace[1605712607] 'applied index is now lower than readState.Index' (duration: 88.790506ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:37.697509Z","caller":"traceutil/trace.go:171","msg":"trace[1893741836] transaction","detail":"{read_only:false; response_revision:12097; number_of_response:1; }","duration":"148.127624ms","start":"2026-09-07T11:11:37.549359Z","end":"2026-09-07T11:11:37.697486Z","steps":["trace[1893741836] 'process raft request' (duration: 147.757756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.697759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.092213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-07T11:11:37.697827Z","caller":"traceutil/trace.go:171","msg":"trace[2105225342] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:12098; }","duration":"126.197625ms","start":"2026-09-07T11:11:37.571608Z","end":"2026-09-07T11:11:37.697806Z","steps":["trace[2105225342] 'agreement among raft nodes before linearized reading' (duration: 125.97544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:37.69796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.08235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-cinder-server\" ","response":"range_response_count:1 size:9601"} {"level":"info","ts":"2026-09-07T11:11:37.698043Z","caller":"traceutil/trace.go:171","msg":"trace[1006449846] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-cinder-server; range_end:; response_count:1; response_revision:12098; }","duration":"147.185603ms","start":"2026-09-07T11:11:37.55082Z","end":"2026-09-07T11:11:37.698006Z","steps":["trace[1006449846] 'agreement among raft nodes before linearized reading' (duration: 146.987129ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:39.324963Z","caller":"traceutil/trace.go:171","msg":"trace[1747721797] linearizableReadLoop","detail":"{readStateIndex:12537; appliedIndex:12535; }","duration":"118.825847ms","start":"2026-09-07T11:11:39.206105Z","end":"2026-09-07T11:11:39.324931Z","steps":["trace[1747721797] 'read index received' (duration: 42.677881ms)","trace[1747721797] 'applied index is now lower than readState.Index' (duration: 76.146576ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:39.325062Z","caller":"traceutil/trace.go:171","msg":"trace[638318831] transaction","detail":"{read_only:false; response_revision:12112; number_of_response:1; }","duration":"121.153657ms","start":"2026-09-07T11:11:39.203864Z","end":"2026-09-07T11:11:39.325018Z","steps":["trace[638318831] 'process raft request' (duration: 120.884211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:39.325271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.150454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-cinder\" ","response":"range_response_count:1 size:3306"} {"level":"info","ts":"2026-09-07T11:11:39.325338Z","caller":"traceutil/trace.go:171","msg":"trace[1785681868] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-cinder; range_end:; response_count:1; response_revision:12112; }","duration":"119.291028ms","start":"2026-09-07T11:11:39.206032Z","end":"2026-09-07T11:11:39.325323Z","steps":["trace[1785681868] 'agreement among raft nodes before linearized reading' (duration: 119.073763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:11:39.325328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.293942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l4m7r\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T11:11:39.325377Z","caller":"traceutil/trace.go:171","msg":"trace[1260748301] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l4m7r; range_end:; response_count:1; response_revision:12112; }","duration":"108.368264ms","start":"2026-09-07T11:11:39.216996Z","end":"2026-09-07T11:11:39.325364Z","steps":["trace[1260748301] 'agreement among raft nodes before linearized reading' (duration: 108.213401ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:39.460331Z","caller":"traceutil/trace.go:171","msg":"trace[1289256725] transaction","detail":"{read_only:false; response_revision:12114; number_of_response:1; }","duration":"127.277969ms","start":"2026-09-07T11:11:39.333015Z","end":"2026-09-07T11:11:39.460293Z","steps":["trace[1289256725] 'process raft request' (duration: 126.940771ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:39.460399Z","caller":"traceutil/trace.go:171","msg":"trace[902369921] transaction","detail":"{read_only:false; response_revision:12115; number_of_response:1; }","duration":"127.015574ms","start":"2026-09-07T11:11:39.333357Z","end":"2026-09-07T11:11:39.460373Z","steps":["trace[902369921] 'process raft request' (duration: 126.789788ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:39.460573Z","caller":"traceutil/trace.go:171","msg":"trace[1028208338] transaction","detail":"{read_only:false; response_revision:12116; number_of_response:1; }","duration":"120.401311ms","start":"2026-09-07T11:11:39.340146Z","end":"2026-09-07T11:11:39.460548Z","steps":["trace[1028208338] 'process raft request' (duration: 120.056813ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:11:39.612082Z","caller":"traceutil/trace.go:171","msg":"trace[254514336] transaction","detail":"{read_only:false; response_revision:12118; number_of_response:1; }","duration":"137.1805ms","start":"2026-09-07T11:11:39.474837Z","end":"2026-09-07T11:11:39.612018Z","steps":["trace[254514336] 'process raft request' (duration: 125.93376ms)","trace[254514336] 'compare' (duration: 10.946784ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:11:45.037669Z","caller":"traceutil/trace.go:171","msg":"trace[464856742] transaction","detail":"{read_only:false; response_revision:12168; number_of_response:1; }","duration":"101.954987ms","start":"2026-09-07T11:11:44.935684Z","end":"2026-09-07T11:11:45.037639Z","steps":["trace[464856742] 'process raft request' (duration: 70.400044ms)","trace[464856742] 'compare' (duration: 31.058423ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:03.546587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10310} {"level":"info","ts":"2026-09-07T11:12:03.670497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10310,"took":"121.024255ms","hash":3963574854,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":25374720,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-07T11:12:03.670551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3963574854,"revision":10310,"compact-revision":7836} {"level":"warn","ts":"2026-09-07T11:12:45.408899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.423068ms","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-09-07T11:12:45.409041Z","caller":"traceutil/trace.go:171","msg":"trace[1141714147] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:12705; }","duration":"353.628692ms","start":"2026-09-07T11:12:45.055367Z","end":"2026-09-07T11:12:45.408996Z","steps":["trace[1141714147] 'count revisions from in-memory index tree' (duration: 353.281005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:45.409101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:45.055338Z","time spent":"353.745395ms","remote":"127.0.0.1:36876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:12:45.409073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.615952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:45.409202Z","caller":"traceutil/trace.go:171","msg":"trace[728852012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12705; }","duration":"434.898118ms","start":"2026-09-07T11:12:44.974271Z","end":"2026-09-07T11:12:45.409169Z","steps":["trace[728852012] 'range keys from in-memory index tree' (duration: 434.51332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:45.409266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:44.974259Z","time spent":"434.99298ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T11:12:45.409607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.4589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-07T11:12:45.409643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.914865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T11:12:45.409722Z","caller":"traceutil/trace.go:171","msg":"trace[1756433693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12705; }","duration":"499.044798ms","start":"2026-09-07T11:12:44.91066Z","end":"2026-09-07T11:12:45.409705Z","steps":["trace[1756433693] 'range keys from in-memory index tree' (duration: 498.697732ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:45.409721Z","caller":"traceutil/trace.go:171","msg":"trace[1577507171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12705; }","duration":"435.634474ms","start":"2026-09-07T11:12:44.97406Z","end":"2026-09-07T11:12:45.409694Z","steps":["trace[1577507171] 'range keys from in-memory index tree' (duration: 435.229105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:45.40977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:44.910636Z","time spent":"499.12353ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T11:12:45.409784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:44.974032Z","time spent":"435.738545ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-07T11:12:45.542144Z","caller":"traceutil/trace.go:171","msg":"trace[1367416277] linearizableReadLoop","detail":"{readStateIndex:13147; appliedIndex:13146; }","duration":"291.771212ms","start":"2026-09-07T11:12:45.250339Z","end":"2026-09-07T11:12:45.542111Z","steps":["trace[1367416277] 'read index received' (duration: 291.481656ms)","trace[1367416277] 'applied index is now lower than readState.Index' (duration: 288.366µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:45.542338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.953986ms","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-09-07T11:12:45.542281Z","caller":"traceutil/trace.go:171","msg":"trace[1430847982] transaction","detail":"{read_only:false; response_revision:12706; number_of_response:1; }","duration":"442.603673ms","start":"2026-09-07T11:12:45.099634Z","end":"2026-09-07T11:12:45.542238Z","steps":["trace[1430847982] 'process raft request' (duration: 442.269846ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:45.542379Z","caller":"traceutil/trace.go:171","msg":"trace[5832233] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:12706; }","duration":"292.11665ms","start":"2026-09-07T11:12:45.250249Z","end":"2026-09-07T11:12:45.542366Z","steps":["trace[5832233] 'agreement among raft nodes before linearized reading' (duration: 291.996897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:45.542521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:45.099605Z","time spent":"442.795977ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:45.591606Z","caller":"traceutil/trace.go:171","msg":"trace[1217206649] transaction","detail":"{read_only:false; response_revision:12707; number_of_response:1; }","duration":"177.783096ms","start":"2026-09-07T11:12:45.413796Z","end":"2026-09-07T11:12:45.591579Z","steps":["trace[1217206649] 'process raft request' (duration: 177.591632ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:45.592011Z","caller":"traceutil/trace.go:171","msg":"trace[782055345] transaction","detail":"{read_only:false; response_revision:12708; number_of_response:1; }","duration":"176.919758ms","start":"2026-09-07T11:12:45.415061Z","end":"2026-09-07T11:12:45.591981Z","steps":["trace[782055345] 'process raft request' (duration: 176.478518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:45.914474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.960948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T11:12:45.914594Z","caller":"traceutil/trace.go:171","msg":"trace[609678709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12708; }","duration":"234.146331ms","start":"2026-09-07T11:12:45.680419Z","end":"2026-09-07T11:12:45.914566Z","steps":["trace[609678709] 'range keys from in-memory index tree' (duration: 233.613239ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:46.065052Z","caller":"traceutil/trace.go:171","msg":"trace[1909042398] transaction","detail":"{read_only:false; response_revision:12709; number_of_response:1; }","duration":"145.352694ms","start":"2026-09-07T11:12:45.919647Z","end":"2026-09-07T11:12:46.064999Z","steps":["trace[1909042398] 'process raft request' (duration: 145.083909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.367698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.041159ms","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-09-07T11:12:46.367791Z","caller":"traceutil/trace.go:171","msg":"trace[1413678068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12709; }","duration":"227.207923ms","start":"2026-09-07T11:12:46.140559Z","end":"2026-09-07T11:12:46.367767Z","steps":["trace[1413678068] 'range keys from in-memory index tree' (duration: 226.816845ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:46.594885Z","caller":"traceutil/trace.go:171","msg":"trace[276966217] transaction","detail":"{read_only:false; response_revision:12710; number_of_response:1; }","duration":"314.699481ms","start":"2026-09-07T11:12:46.280154Z","end":"2026-09-07T11:12:46.594853Z","steps":["trace[276966217] 'process raft request' (duration: 314.496627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.595082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:46.280125Z","time spent":"314.850484ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:46.629688Z","caller":"traceutil/trace.go:171","msg":"trace[1374819048] transaction","detail":"{read_only:false; response_revision:12712; number_of_response:1; }","duration":"109.871297ms","start":"2026-09-07T11:12:46.519783Z","end":"2026-09-07T11:12:46.629655Z","steps":["trace[1374819048] 'process raft request' (duration: 109.795605ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:46.629718Z","caller":"traceutil/trace.go:171","msg":"trace[754519634] transaction","detail":"{read_only:false; response_revision:12711; number_of_response:1; }","duration":"257.294905ms","start":"2026-09-07T11:12:46.372387Z","end":"2026-09-07T11:12:46.629682Z","steps":["trace[754519634] 'process raft request' (duration: 257.03508ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:46.629935Z","caller":"traceutil/trace.go:171","msg":"trace[924355662] transaction","detail":"{read_only:false; response_revision:12713; number_of_response:1; }","duration":"105.662997ms","start":"2026-09-07T11:12:46.524254Z","end":"2026-09-07T11:12:46.629917Z","steps":["trace[924355662] 'process raft request' (duration: 105.365661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.946828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.02554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:46.946931Z","caller":"traceutil/trace.go:171","msg":"trace[866379343] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12713; }","duration":"117.192823ms","start":"2026-09-07T11:12:46.829716Z","end":"2026-09-07T11:12:46.946909Z","steps":["trace[866379343] 'range keys from in-memory index tree' (duration: 116.896747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.947045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.514975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:46.947141Z","caller":"traceutil/trace.go:171","msg":"trace[1735868614] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12713; }","duration":"239.666529ms","start":"2026-09-07T11:12:46.707453Z","end":"2026-09-07T11:12:46.94712Z","steps":["trace[1735868614] 'range keys from in-memory index tree' (duration: 239.411313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.947307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.50665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:46.94734Z","caller":"traceutil/trace.go:171","msg":"trace[1174802555] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12713; }","duration":"117.584441ms","start":"2026-09-07T11:12:46.829745Z","end":"2026-09-07T11:12:46.947329Z","steps":["trace[1174802555] 'range keys from in-memory index tree' (duration: 117.423168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.947278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.135453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:12:46.947495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.578984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:12:46.94754Z","caller":"traceutil/trace.go:171","msg":"trace[1465524841] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12713; }","duration":"118.47093ms","start":"2026-09-07T11:12:46.829059Z","end":"2026-09-07T11:12:46.94753Z","steps":["trace[1465524841] 'range keys from in-memory index tree' (duration: 117.820616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.947536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.685073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:46.947648Z","caller":"traceutil/trace.go:171","msg":"trace[447386914] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12713; }","duration":"117.869037ms","start":"2026-09-07T11:12:46.829761Z","end":"2026-09-07T11:12:46.94763Z","steps":["trace[447386914] 'range keys from in-memory index tree' (duration: 117.588772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.947637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.822863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-09-07T11:12:46.947744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.778766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-09-07T11:12:46.947584Z","caller":"traceutil/trace.go:171","msg":"trace[1316272258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12713; }","duration":"134.701117ms","start":"2026-09-07T11:12:46.812857Z","end":"2026-09-07T11:12:46.947558Z","steps":["trace[1316272258] 'range keys from in-memory index tree' (duration: 134.39116ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:46.947815Z","caller":"traceutil/trace.go:171","msg":"trace[462442633] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12713; }","duration":"117.861607ms","start":"2026-09-07T11:12:46.82993Z","end":"2026-09-07T11:12:46.947792Z","steps":["trace[462442633] 'range keys from in-memory index tree' (duration: 117.664033ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:46.947854Z","caller":"traceutil/trace.go:171","msg":"trace[236109952] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12713; }","duration":"233.953687ms","start":"2026-09-07T11:12:46.713774Z","end":"2026-09-07T11:12:46.947727Z","steps":["trace[236109952] 'range keys from in-memory index tree' (duration: 233.614369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.947922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.218749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:46.947971Z","caller":"traceutil/trace.go:171","msg":"trace[968472879] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12713; }","duration":"239.29081ms","start":"2026-09-07T11:12:46.708664Z","end":"2026-09-07T11:12:46.947955Z","steps":["trace[968472879] 'range keys from in-memory index tree' (duration: 239.153347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.947976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.624458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-07T11:12:46.948011Z","caller":"traceutil/trace.go:171","msg":"trace[1664304065] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12713; }","duration":"239.720189ms","start":"2026-09-07T11:12:46.708281Z","end":"2026-09-07T11:12:46.948001Z","steps":["trace[1664304065] 'range keys from in-memory index tree' (duration: 239.520315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.948032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.739278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:46.948087Z","caller":"traceutil/trace.go:171","msg":"trace[1999696023] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12713; }","duration":"238.823182ms","start":"2026-09-07T11:12:46.70925Z","end":"2026-09-07T11:12:46.948073Z","steps":["trace[1999696023] 'range keys from in-memory index tree' (duration: 238.591305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:46.948238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.512486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:46.948378Z","caller":"traceutil/trace.go:171","msg":"trace[1889696603] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12713; }","duration":"239.67102ms","start":"2026-09-07T11:12:46.708687Z","end":"2026-09-07T11:12:46.948359Z","steps":["trace[1889696603] 'range keys from in-memory index tree' (duration: 239.360843ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:47.306939Z","caller":"traceutil/trace.go:171","msg":"trace[1944517264] transaction","detail":"{read_only:false; response_revision:12714; number_of_response:1; }","duration":"352.12071ms","start":"2026-09-07T11:12:46.954782Z","end":"2026-09-07T11:12:47.306903Z","steps":["trace[1944517264] 'process raft request' (duration: 277.743081ms)","trace[1944517264] 'compare' (duration: 74.106074ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:47.307134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:46.95475Z","time spent":"352.273004ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:47.965064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.187774ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:47.965239Z","caller":"traceutil/trace.go:171","msg":"trace[17750446] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:12714; }","duration":"412.378928ms","start":"2026-09-07T11:12:47.552839Z","end":"2026-09-07T11:12:47.965218Z","steps":["trace[17750446] 'range keys from in-memory index tree' (duration: 412.170074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:47.965273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.029316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:12:47.965423Z","caller":"traceutil/trace.go:171","msg":"trace[1665689822] linearizableReadLoop","detail":"{readStateIndex:13157; appliedIndex:13156; }","duration":"721.247105ms","start":"2026-09-07T11:12:47.244157Z","end":"2026-09-07T11:12:47.965404Z","steps":["trace[1665689822] 'read index received' (duration: 165.014845ms)","trace[1665689822] 'applied index is now lower than readState.Index' (duration: 556.23062ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:47.965509Z","caller":"traceutil/trace.go:171","msg":"trace[1789069169] transaction","detail":"{read_only:false; response_revision:12715; number_of_response:1; }","duration":"935.01353ms","start":"2026-09-07T11:12:47.030484Z","end":"2026-09-07T11:12:47.965497Z","steps":["trace[1789069169] 'process raft request' (duration: 378.657227ms)","trace[1789069169] 'compare' (duration: 555.678528ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:47.965598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.030444Z","time spent":"935.090222ms","remote":"127.0.0.1:38356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:47.965812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.656903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:47.965863Z","caller":"traceutil/trace.go:171","msg":"trace[362418405] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:12715; }","duration":"721.745046ms","start":"2026-09-07T11:12:47.244103Z","end":"2026-09-07T11:12:47.965848Z","steps":["trace[362418405] 'agreement among raft nodes before linearized reading' (duration: 721.634853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:47.965899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.24408Z","time spent":"721.810326ms","remote":"127.0.0.1:41418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:12:48.412802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.605533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:12:48.413053Z","caller":"traceutil/trace.go:171","msg":"trace[1892158397] linearizableReadLoop","detail":"{readStateIndex:13159; appliedIndex:13157; }","duration":"447.572389ms","start":"2026-09-07T11:12:47.965457Z","end":"2026-09-07T11:12:48.413029Z","steps":["trace[1892158397] 'read index received' (duration: 151.637459ms)","trace[1892158397] 'applied index is now lower than readState.Index' (duration: 295.93348ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:48.413197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.875212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:12:48.413303Z","caller":"traceutil/trace.go:171","msg":"trace[764073663] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12717; }","duration":"732.031555ms","start":"2026-09-07T11:12:47.681233Z","end":"2026-09-07T11:12:48.413264Z","steps":["trace[764073663] 'agreement among raft nodes before linearized reading' (duration: 731.86793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.413319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.01128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-07T11:12:48.41336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.681209Z","time spent":"732.132667ms","remote":"127.0.0.1:41602","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-09-07T11:12:48.413367Z","caller":"traceutil/trace.go:171","msg":"trace[1386979655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12717; }","duration":"817.109923ms","start":"2026-09-07T11:12:47.596242Z","end":"2026-09-07T11:12:48.413352Z","steps":["trace[1386979655] 'agreement among raft nodes before linearized reading' (duration: 816.910878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.41341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.596225Z","time spent":"817.173393ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-07T11:12:48.413419Z","caller":"traceutil/trace.go:171","msg":"trace[1927955375] transaction","detail":"{read_only:false; response_revision:12716; number_of_response:1; }","duration":"933.176891ms","start":"2026-09-07T11:12:47.480207Z","end":"2026-09-07T11:12:48.413384Z","steps":["trace[1927955375] 'process raft request' (duration: 636.876902ms)","trace[1927955375] 'compare' (duration: 295.364779ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:48.413446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122819481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:12:48.413476Z","caller":"traceutil/trace.go:171","msg":"trace[809486909] transaction","detail":"{read_only:false; response_revision:12717; number_of_response:1; }","duration":"864.851242ms","start":"2026-09-07T11:12:47.548569Z","end":"2026-09-07T11:12:48.413421Z","steps":["trace[809486909] 'process raft request' (duration: 864.361621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.413556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.507597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:12:48.413548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.302101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-09-07T11:12:48.413601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.48018Z","time spent":"933.310814ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:48.4136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.425864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-07T11:12:48.413602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.330226ms","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-09-07T11:12:48.413619Z","caller":"traceutil/trace.go:171","msg":"trace[169628232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12717; }","duration":"439.605879ms","start":"2026-09-07T11:12:47.973995Z","end":"2026-09-07T11:12:48.413601Z","steps":["trace[169628232] 'agreement among raft nodes before linearized reading' (duration: 439.502467ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:48.413653Z","caller":"traceutil/trace.go:171","msg":"trace[93488695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12717; }","duration":"342.528967ms","start":"2026-09-07T11:12:48.07111Z","end":"2026-09-07T11:12:48.413639Z","steps":["trace[93488695] 'agreement among raft nodes before linearized reading' (duration: 342.342243ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:48.413639Z","caller":"traceutil/trace.go:171","msg":"trace[1780172516] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:12717; }","duration":"445.439414ms","start":"2026-09-07T11:12:47.968175Z","end":"2026-09-07T11:12:48.413614Z","steps":["trace[1780172516] 'agreement among raft nodes before linearized reading' (duration: 445.207399ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:48.413674Z","caller":"traceutil/trace.go:171","msg":"trace[404540643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12717; }","duration":"817.439699ms","start":"2026-09-07T11:12:47.596213Z","end":"2026-09-07T11:12:48.413653Z","steps":["trace[404540643] 'agreement among raft nodes before linearized reading' (duration: 817.189814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.413674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.973978Z","time spent":"439.684441ms","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T11:12:48.413687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.071082Z","time spent":"342.597518ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T11:12:48.41371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.596192Z","time spent":"817.510051ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T11:12:48.413719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.968155Z","time spent":"445.545866ms","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-09-07T11:12:48.413713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.548545Z","time spent":"865.041015ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:48.413649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.843861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l4m7r\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-09-07T11:12:48.413656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.780051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:48.413819Z","caller":"traceutil/trace.go:171","msg":"trace[1195957245] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l4m7r; range_end:; response_count:1; response_revision:12717; }","duration":"327.061656ms","start":"2026-09-07T11:12:48.086737Z","end":"2026-09-07T11:12:48.413799Z","steps":["trace[1195957245] 'agreement among raft nodes before linearized reading' (duration: 326.77631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.413898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.086709Z","time spent":"327.171008ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-l4m7r\" "} {"level":"info","ts":"2026-09-07T11:12:48.413837Z","caller":"traceutil/trace.go:171","msg":"trace[1818996195] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:12717; }","duration":"408.967895ms","start":"2026-09-07T11:12:48.004844Z","end":"2026-09-07T11:12:48.413812Z","steps":["trace[1818996195] 'agreement among raft nodes before linearized reading' (duration: 408.650298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.413982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.004771Z","time spent":"409.190469ms","remote":"127.0.0.1:37614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-09-07T11:12:48.414409Z","caller":"traceutil/trace.go:171","msg":"trace[38268072] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:12717; }","duration":"1.123856083s","start":"2026-09-07T11:12:47.290518Z","end":"2026-09-07T11:12:48.414374Z","steps":["trace[38268072] 'agreement among raft nodes before linearized reading' (duration: 1.12274906s)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.414502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:47.290492Z","time spent":"1.123990635s","remote":"127.0.0.1:38660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":14,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:12:48.933494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.405596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:12:48.933688Z","caller":"traceutil/trace.go:171","msg":"trace[1203410699] transaction","detail":"{read_only:false; response_revision:12720; number_of_response:1; }","duration":"513.14588ms","start":"2026-09-07T11:12:48.420526Z","end":"2026-09-07T11:12:48.933672Z","steps":["trace[1203410699] 'process raft request' (duration: 513.113399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.933737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.420503Z","time spent":"513.2112ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:48.933743Z","caller":"traceutil/trace.go:171","msg":"trace[1784716636] transaction","detail":"{read_only:false; response_revision:12719; number_of_response:1; }","duration":"514.508648ms","start":"2026-09-07T11:12:48.419202Z","end":"2026-09-07T11:12:48.933711Z","steps":["trace[1784716636] 'process raft request' (duration: 514.371985ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:48.93391Z","caller":"traceutil/trace.go:171","msg":"trace[1350380690] linearizableReadLoop","detail":"{readStateIndex:13161; appliedIndex:13159; }","duration":"514.247972ms","start":"2026-09-07T11:12:48.419649Z","end":"2026-09-07T11:12:48.933897Z","steps":["trace[1350380690] 'read index received' (duration: 176.356076ms)","trace[1350380690] 'applied index is now lower than readState.Index' (duration: 337.889976ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:48.933901Z","caller":"traceutil/trace.go:171","msg":"trace[917117983] transaction","detail":"{read_only:false; response_revision:12718; number_of_response:1; }","duration":"514.705763ms","start":"2026-09-07T11:12:48.419158Z","end":"2026-09-07T11:12:48.933863Z","steps":["trace[917117983] 'process raft request' (duration: 176.846627ms)","trace[917117983] 'compare' (duration: 337.071189ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:48.934002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.419162Z","time spent":"514.654542ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:48.934082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.41914Z","time spent":"514.839825ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:48.934625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.970818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:48 size:447782"} {"level":"info","ts":"2026-09-07T11:12:48.93465Z","caller":"traceutil/trace.go:171","msg":"trace[974612816] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:48; response_revision:12720; }","duration":"515.02618ms","start":"2026-09-07T11:12:48.419616Z","end":"2026-09-07T11:12:48.934642Z","steps":["trace[974612816] 'agreement among raft nodes before linearized reading' (duration: 514.352995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:48.934675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.419607Z","time spent":"515.06165ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":48,"response size":447806,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-07T11:12:49.189582Z","caller":"traceutil/trace.go:171","msg":"trace[1307738740] transaction","detail":"{read_only:false; response_revision:12722; number_of_response:1; }","duration":"552.852778ms","start":"2026-09-07T11:12:48.636697Z","end":"2026-09-07T11:12:49.18955Z","steps":["trace[1307738740] 'process raft request' (duration: 552.704925ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:49.189667Z","caller":"traceutil/trace.go:171","msg":"trace[1534328929] linearizableReadLoop","detail":"{readStateIndex:13165; appliedIndex:13162; }","duration":"255.704812ms","start":"2026-09-07T11:12:48.933928Z","end":"2026-09-07T11:12:49.189633Z","steps":["trace[1534328929] 'read index received' (duration: 188.932746ms)","trace[1534328929] 'applied index is now lower than readState.Index' (duration: 66.770136ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:49.189785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.63668Z","time spent":"552.997062ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:49.189903Z","caller":"traceutil/trace.go:171","msg":"trace[359069579] transaction","detail":"{read_only:false; response_revision:12721; number_of_response:1; }","duration":"588.730114ms","start":"2026-09-07T11:12:48.601138Z","end":"2026-09-07T11:12:49.189868Z","steps":["trace[359069579] 'process raft request' (duration: 521.739113ms)","trace[359069579] 'compare' (duration: 66.316256ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:49.19009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.818888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-09-07T11:12:49.19011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.601104Z","time spent":"588.881507ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:49.190156Z","caller":"traceutil/trace.go:171","msg":"trace[992288051] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12723; }","duration":"236.93853ms","start":"2026-09-07T11:12:48.953202Z","end":"2026-09-07T11:12:49.190141Z","steps":["trace[992288051] 'agreement among raft nodes before linearized reading' (duration: 236.799848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.19013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.126443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:49.190154Z","caller":"traceutil/trace.go:171","msg":"trace[1625465226] transaction","detail":"{read_only:false; response_revision:12723; number_of_response:1; }","duration":"553.210366ms","start":"2026-09-07T11:12:48.636735Z","end":"2026-09-07T11:12:49.189945Z","steps":["trace[1625465226] 'process raft request' (duration: 552.766647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.448779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:12:49.190236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.297166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:49.190388Z","caller":"traceutil/trace.go:171","msg":"trace[1367753810] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12723; }","duration":"236.558412ms","start":"2026-09-07T11:12:48.953813Z","end":"2026-09-07T11:12:49.190371Z","steps":["trace[1367753810] 'agreement among raft nodes before linearized reading' (duration: 236.45086ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:49.190406Z","caller":"traceutil/trace.go:171","msg":"trace[672414690] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12723; }","duration":"236.486791ms","start":"2026-09-07T11:12:48.953903Z","end":"2026-09-07T11:12:49.190389Z","steps":["trace[672414690] 'agreement among raft nodes before linearized reading' (duration: 236.212995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.746714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-09-07T11:12:49.190465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.254055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:49.190525Z","caller":"traceutil/trace.go:171","msg":"trace[961557802] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12723; }","duration":"235.811396ms","start":"2026-09-07T11:12:48.954694Z","end":"2026-09-07T11:12:49.190506Z","steps":["trace[961557802] 'agreement among raft nodes before linearized reading' (duration: 235.722464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.636717Z","time spent":"553.632835ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:49.190266Z","caller":"traceutil/trace.go:171","msg":"trace[2034236192] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12723; }","duration":"236.294947ms","start":"2026-09-07T11:12:48.953958Z","end":"2026-09-07T11:12:49.190253Z","steps":["trace[2034236192] 'agreement among raft nodes before linearized reading' (duration: 236.115673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.743197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:49.190695Z","caller":"traceutil/trace.go:171","msg":"trace[2033208282] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12723; }","duration":"236.994062ms","start":"2026-09-07T11:12:48.953692Z","end":"2026-09-07T11:12:49.190686Z","steps":["trace[2033208282] 'agreement among raft nodes before linearized reading' (duration: 236.735157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.233934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:12:49.190342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.642303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-09-07T11:12:49.190601Z","caller":"traceutil/trace.go:171","msg":"trace[1076238671] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12723; }","duration":"236.406509ms","start":"2026-09-07T11:12:48.954139Z","end":"2026-09-07T11:12:49.190546Z","steps":["trace[1076238671] 'agreement among raft nodes before linearized reading' (duration: 236.218655ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:49.190757Z","caller":"traceutil/trace.go:171","msg":"trace[335438482] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:12723; }","duration":"168.618781ms","start":"2026-09-07T11:12:49.022117Z","end":"2026-09-07T11:12:49.190736Z","steps":["trace[335438482] 'agreement among raft nodes before linearized reading' (duration: 168.249863ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:49.190764Z","caller":"traceutil/trace.go:171","msg":"trace[2053786338] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12723; }","duration":"236.075981ms","start":"2026-09-07T11:12:48.954671Z","end":"2026-09-07T11:12:49.190747Z","steps":["trace[2053786338] 'agreement among raft nodes before linearized reading' (duration: 235.576471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.739235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:49.190859Z","caller":"traceutil/trace.go:171","msg":"trace[1354326269] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12723; }","duration":"236.852368ms","start":"2026-09-07T11:12:48.953996Z","end":"2026-09-07T11:12:49.190848Z","steps":["trace[1354326269] 'agreement among raft nodes before linearized reading' (duration: 236.628684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.91624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:49.190922Z","caller":"traceutil/trace.go:171","msg":"trace[538746610] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12723; }","duration":"236.971571ms","start":"2026-09-07T11:12:48.953943Z","end":"2026-09-07T11:12:49.190915Z","steps":["trace[538746610] 'agreement among raft nodes before linearized reading' (duration: 236.91574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.600195ms","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":"warn","ts":"2026-09-07T11:12:49.190914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.992523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T11:12:49.190975Z","caller":"traceutil/trace.go:171","msg":"trace[823156568] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:12723; }","duration":"518.106956ms","start":"2026-09-07T11:12:48.672854Z","end":"2026-09-07T11:12:49.190961Z","steps":["trace[823156568] 'agreement among raft nodes before linearized reading' (duration: 517.215587ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:49.190973Z","caller":"traceutil/trace.go:171","msg":"trace[1183876706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12723; }","duration":"555.465294ms","start":"2026-09-07T11:12:48.635488Z","end":"2026-09-07T11:12:49.190953Z","steps":["trace[1183876706] 'agreement among raft nodes before linearized reading' (duration: 554.438562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.190956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.97994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:12:49.191027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.635471Z","time spent":"555.540645ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-07T11:12:49.191063Z","caller":"traceutil/trace.go:171","msg":"trace[254898925] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12723; }","duration":"237.130834ms","start":"2026-09-07T11:12:48.953916Z","end":"2026-09-07T11:12:49.191046Z","steps":["trace[254898925] 'agreement among raft nodes before linearized reading' (duration: 236.95365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:49.191016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:48.672828Z","time spent":"518.179277ms","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1165,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:12:49.811862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16085908421257672551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-07T11:12:50.34433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.052314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:12:50.344459Z","caller":"traceutil/trace.go:171","msg":"trace[2060828288] transaction","detail":"{read_only:false; response_revision:12724; number_of_response:1; }","duration":"1.146901665s","start":"2026-09-07T11:12:49.19752Z","end":"2026-09-07T11:12:50.344421Z","steps":["trace[2060828288] 'process raft request' (duration: 982.629846ms)","trace[2060828288] 'compare' (duration: 163.85355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:50.34452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:49.19749Z","time spent":"1.147001777s","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:50.677493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.553544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:12:50.677729Z","caller":"traceutil/trace.go:171","msg":"trace[780522555] linearizableReadLoop","detail":"{readStateIndex:13168; appliedIndex:13165; }","duration":"1.366358322s","start":"2026-09-07T11:12:49.311348Z","end":"2026-09-07T11:12:50.677706Z","steps":["trace[780522555] 'read index received' (duration: 868.910777ms)","trace[780522555] 'applied index is now lower than readState.Index' (duration: 497.445895ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:50.677761Z","caller":"traceutil/trace.go:171","msg":"trace[1139305573] transaction","detail":"{read_only:false; response_revision:12725; number_of_response:1; }","duration":"1.449519329s","start":"2026-09-07T11:12:49.228228Z","end":"2026-09-07T11:12:50.677748Z","steps":["trace[1139305573] 'process raft request' (duration: 1.247479304s)","trace[1139305573] 'compare' (duration: 201.397601ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:50.677759Z","caller":"traceutil/trace.go:171","msg":"trace[1944207108] transaction","detail":"{read_only:false; response_revision:12726; number_of_response:1; }","duration":"1.38966175s","start":"2026-09-07T11:12:49.288075Z","end":"2026-09-07T11:12:50.677737Z","steps":["trace[1944207108] 'process raft request' (duration: 1.389539437s)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:50.677815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:49.228197Z","time spent":"1.44959171s","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:50.677854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:49.288048Z","time spent":"1.389752731s","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:50.678144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36677275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:12:50.678244Z","caller":"traceutil/trace.go:171","msg":"trace[348204315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12726; }","duration":"1.366943435s","start":"2026-09-07T11:12:49.311275Z","end":"2026-09-07T11:12:50.678219Z","steps":["trace[348204315] 'agreement among raft nodes before linearized reading' (duration: 1.366527956s)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:50.678333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:49.311242Z","time spent":"1.367076327s","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-07T11:12:50.999606Z","caller":"traceutil/trace.go:171","msg":"trace[1284557746] linearizableReadLoop","detail":"{readStateIndex:13169; appliedIndex:13168; }","duration":"321.799274ms","start":"2026-09-07T11:12:50.677778Z","end":"2026-09-07T11:12:50.999578Z","steps":["trace[1284557746] 'read index received' (duration: 291.054427ms)","trace[1284557746] 'applied index is now lower than readState.Index' (duration: 30.743627ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:50.999845Z","caller":"traceutil/trace.go:171","msg":"trace[832672047] transaction","detail":"{read_only:false; response_revision:12727; number_of_response:1; }","duration":"579.414815ms","start":"2026-09-07T11:12:50.420416Z","end":"2026-09-07T11:12:50.999831Z","steps":["trace[832672047] 'process raft request' (duration: 548.405993ms)","trace[832672047] 'compare' (duration: 30.609804ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:50.99996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:50.420379Z","time spent":"579.498997ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:51.000158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025490182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.000194Z","caller":"traceutil/trace.go:171","msg":"trace[298788070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12727; }","duration":"1.025568003s","start":"2026-09-07T11:12:49.974616Z","end":"2026-09-07T11:12:51.000184Z","steps":["trace[298788070] 'agreement among raft nodes before linearized reading' (duration: 1.025508912s)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.000221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:49.974584Z","time spent":"1.025631005s","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T11:12:51.000218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112329056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.000337Z","caller":"traceutil/trace.go:171","msg":"trace[740104373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12727; }","duration":"1.112476229s","start":"2026-09-07T11:12:49.887806Z","end":"2026-09-07T11:12:51.000282Z","steps":["trace[740104373] 'agreement among raft nodes before linearized reading' (duration: 1.112326296s)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.000324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.575092509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:12:51.000385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:49.88778Z","time spent":"1.112596172s","remote":"127.0.0.1:38328","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-09-07T11:12:51.000426Z","caller":"traceutil/trace.go:171","msg":"trace[465642200] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:12727; }","duration":"1.575282324s","start":"2026-09-07T11:12:49.425114Z","end":"2026-09-07T11:12:51.000396Z","steps":["trace[465642200] 'agreement among raft nodes before linearized reading' (duration: 1.575048889s)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.000501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:49.425096Z","time spent":"1.575386946s","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:12:51.000678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610886835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-07T11:12:51.000764Z","caller":"traceutil/trace.go:171","msg":"trace[870133953] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12727; }","duration":"1.611042368s","start":"2026-09-07T11:12:49.389701Z","end":"2026-09-07T11:12:51.000743Z","steps":["trace[870133953] 'agreement among raft nodes before linearized reading' (duration: 1.610446596s)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.001147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:49.389672Z","time spent":"1.611456116s","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-07T11:12:51.028935Z","caller":"traceutil/trace.go:171","msg":"trace[1588800452] transaction","detail":"{read_only:false; response_revision:12728; number_of_response:1; }","duration":"346.330756ms","start":"2026-09-07T11:12:50.682572Z","end":"2026-09-07T11:12:51.028903Z","steps":["trace[1588800452] 'process raft request' (duration: 345.976489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.029081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:50.682549Z","time spent":"346.451538ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:12:51.02915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.018864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:12:51.029254Z","caller":"traceutil/trace.go:171","msg":"trace[196136263] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12728; }","duration":"268.175927ms","start":"2026-09-07T11:12:50.761051Z","end":"2026-09-07T11:12:51.029227Z","steps":["trace[196136263] 'agreement among raft nodes before linearized reading' (duration: 268.010444ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:51.219145Z","caller":"traceutil/trace.go:171","msg":"trace[1857058217] linearizableReadLoop","detail":"{readStateIndex:13171; appliedIndex:13170; }","duration":"184.790846ms","start":"2026-09-07T11:12:51.034334Z","end":"2026-09-07T11:12:51.219125Z","steps":["trace[1857058217] 'read index received' (duration: 91.594416ms)","trace[1857058217] 'applied index is now lower than readState.Index' (duration: 93.19497ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:51.219159Z","caller":"traceutil/trace.go:171","msg":"trace[320810071] transaction","detail":"{read_only:false; response_revision:12729; number_of_response:1; }","duration":"185.634374ms","start":"2026-09-07T11:12:51.033444Z","end":"2026-09-07T11:12:51.219078Z","steps":["trace[320810071] 'process raft request' (duration: 92.215419ms)","trace[320810071] 'compare' (duration: 93.139789ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:51.219333Z","caller":"traceutil/trace.go:171","msg":"trace[1128219884] transaction","detail":"{read_only:false; response_revision:12730; number_of_response:1; }","duration":"184.98134ms","start":"2026-09-07T11:12:51.03434Z","end":"2026-09-07T11:12:51.219321Z","steps":["trace[1128219884] 'process raft request' (duration: 184.743495ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:51.219602Z","caller":"traceutil/trace.go:171","msg":"trace[466217617] transaction","detail":"{read_only:false; response_revision:12731; number_of_response:1; }","duration":"184.191583ms","start":"2026-09-07T11:12:51.035369Z","end":"2026-09-07T11:12:51.21956Z","steps":["trace[466217617] 'process raft request' (duration: 183.908027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.219904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.539793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:48 size:447782"} {"level":"info","ts":"2026-09-07T11:12:51.219986Z","caller":"traceutil/trace.go:171","msg":"trace[381980106] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:48; response_revision:12729; }","duration":"185.684505ms","start":"2026-09-07T11:12:51.034283Z","end":"2026-09-07T11:12:51.219967Z","steps":["trace[381980106] 'agreement among raft nodes before linearized reading' (duration: 184.938099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.67599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.290806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:12:51.676104Z","caller":"traceutil/trace.go:171","msg":"trace[332649559] transaction","detail":"{read_only:false; response_revision:12732; number_of_response:1; }","duration":"478.606303ms","start":"2026-09-07T11:12:51.197478Z","end":"2026-09-07T11:12:51.676085Z","steps":["trace[332649559] 'process raft request' (duration: 146.098532ms)","trace[332649559] 'compare' (duration: 332.154663ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:51.676171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197446Z","time spent":"478.691114ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:51.708374Z","caller":"traceutil/trace.go:171","msg":"trace[1301954048] transaction","detail":"{read_only:false; response_revision:12733; number_of_response:1; }","duration":"510.79901ms","start":"2026-09-07T11:12:51.19753Z","end":"2026-09-07T11:12:51.708329Z","steps":["trace[1301954048] 'process raft request' (duration: 510.474133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.708598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.163523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.708654Z","caller":"traceutil/trace.go:171","msg":"trace[975436855] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:12734; }","duration":"611.292755ms","start":"2026-09-07T11:12:51.097349Z","end":"2026-09-07T11:12:51.708642Z","steps":["trace[975436855] 'agreement among raft nodes before linearized reading' (duration: 611.165253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.70863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.751988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-09-07T11:12:51.708691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.097325Z","time spent":"611.356487ms","remote":"127.0.0.1:38772","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-09-07T11:12:51.708647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.81854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.709137Z","caller":"traceutil/trace.go:171","msg":"trace[1451449334] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12734; }","duration":"511.33735ms","start":"2026-09-07T11:12:51.197786Z","end":"2026-09-07T11:12:51.709124Z","steps":["trace[1451449334] 'agreement among raft nodes before linearized reading' (duration: 510.771159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.709177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.19778Z","time spent":"511.386571ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" "} {"level":"warn","ts":"2026-09-07T11:12:51.708707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.874631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.709258Z","caller":"traceutil/trace.go:171","msg":"trace[196271980] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12734; }","duration":"511.431143ms","start":"2026-09-07T11:12:51.197814Z","end":"2026-09-07T11:12:51.709245Z","steps":["trace[196271980] 'agreement among raft nodes before linearized reading' (duration: 510.876731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.709342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197808Z","time spent":"511.520315ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"info","ts":"2026-09-07T11:12:51.708721Z","caller":"traceutil/trace.go:171","msg":"trace[1896631853] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12734; }","duration":"510.911492ms","start":"2026-09-07T11:12:51.197789Z","end":"2026-09-07T11:12:51.708701Z","steps":["trace[1896631853] 'agreement among raft nodes before linearized reading' (duration: 510.600455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.709447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197763Z","time spent":"511.660008ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5101,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"warn","ts":"2026-09-07T11:12:51.708741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.929942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.709557Z","caller":"traceutil/trace.go:171","msg":"trace[1987250107] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12734; }","duration":"511.761869ms","start":"2026-09-07T11:12:51.197786Z","end":"2026-09-07T11:12:51.709548Z","steps":["trace[1987250107] 'agreement among raft nodes before linearized reading' (duration: 510.936862ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.709585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197777Z","time spent":"511.80117ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-09-07T11:12:51.708739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197516Z","time spent":"511.027325ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:51.708401Z","caller":"traceutil/trace.go:171","msg":"trace[127611121] linearizableReadLoop","detail":"{readStateIndex:13176; appliedIndex:13173; }","duration":"489.152558ms","start":"2026-09-07T11:12:51.219221Z","end":"2026-09-07T11:12:51.708374Z","steps":["trace[127611121] 'read index received' (duration: 124.50179ms)","trace[127611121] 'applied index is now lower than readState.Index' (duration: 364.648628ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:51.708776Z","caller":"traceutil/trace.go:171","msg":"trace[333493357] transaction","detail":"{read_only:false; response_revision:12734; number_of_response:1; }","duration":"511.063625ms","start":"2026-09-07T11:12:51.1977Z","end":"2026-09-07T11:12:51.708764Z","steps":["trace[333493357] 'process raft request' (duration: 510.449142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.708827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.971063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.709885Z","caller":"traceutil/trace.go:171","msg":"trace[1041561004] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12734; }","duration":"512.041095ms","start":"2026-09-07T11:12:51.197829Z","end":"2026-09-07T11:12:51.70987Z","steps":["trace[1041561004] 'agreement among raft nodes before linearized reading' (duration: 510.971683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.709928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197818Z","time spent":"512.095956ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-09-07T11:12:51.708847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.175278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:12:51.709941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197666Z","time spent":"512.120948ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:12:51.710019Z","caller":"traceutil/trace.go:171","msg":"trace[852536183] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12734; }","duration":"512.350213ms","start":"2026-09-07T11:12:51.197645Z","end":"2026-09-07T11:12:51.709996Z","steps":["trace[852536183] 'agreement among raft nodes before linearized reading' (duration: 511.140687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.710251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197639Z","time spent":"512.593548ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-09-07T11:12:51.708896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.199309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.710385Z","caller":"traceutil/trace.go:171","msg":"trace[948556253] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12734; }","duration":"512.704572ms","start":"2026-09-07T11:12:51.197662Z","end":"2026-09-07T11:12:51.710367Z","steps":["trace[948556253] 'agreement among raft nodes before linearized reading' (duration: 511.212089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.708898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.860031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-09-07T11:12:51.710466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197649Z","time spent":"512.800473ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"info","ts":"2026-09-07T11:12:51.710501Z","caller":"traceutil/trace.go:171","msg":"trace[145863037] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12734; }","duration":"512.478865ms","start":"2026-09-07T11:12:51.198009Z","end":"2026-09-07T11:12:51.710487Z","steps":["trace[145863037] 'agreement among raft nodes before linearized reading' (duration: 510.780278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.708917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.877951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:12:51.710581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197996Z","time spent":"512.569668ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5101,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"info","ts":"2026-09-07T11:12:51.710638Z","caller":"traceutil/trace.go:171","msg":"trace[585827231] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12734; }","duration":"512.605098ms","start":"2026-09-07T11:12:51.198014Z","end":"2026-09-07T11:12:51.710619Z","steps":["trace[585827231] 'agreement among raft nodes before linearized reading' (duration: 510.880561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.710711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.198006Z","time spent":"512.687ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" "} {"level":"warn","ts":"2026-09-07T11:12:51.708961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.492775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-07T11:12:51.711Z","caller":"traceutil/trace.go:171","msg":"trace[820057403] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12734; }","duration":"513.533349ms","start":"2026-09-07T11:12:51.197453Z","end":"2026-09-07T11:12:51.710986Z","steps":["trace[820057403] 'agreement among raft nodes before linearized reading' (duration: 511.448194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.711067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197389Z","time spent":"513.662921ms","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/cinder-api\" "} {"level":"warn","ts":"2026-09-07T11:12:51.708864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.013414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:51.711173Z","caller":"traceutil/trace.go:171","msg":"trace[2000241558] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12734; }","duration":"513.353644ms","start":"2026-09-07T11:12:51.197808Z","end":"2026-09-07T11:12:51.711161Z","steps":["trace[2000241558] 'agreement among raft nodes before linearized reading' (duration: 511.034945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:51.711241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:12:51.197792Z","time spent":"513.438066ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"info","ts":"2026-09-07T11:12:52.376518Z","caller":"traceutil/trace.go:171","msg":"trace[1838714882] linearizableReadLoop","detail":"{readStateIndex:13177; appliedIndex:13176; }","duration":"199.886878ms","start":"2026-09-07T11:12:52.1766Z","end":"2026-09-07T11:12:52.376487Z","steps":["trace[1838714882] 'read index received' (duration: 99.942135ms)","trace[1838714882] 'applied index is now lower than readState.Index' (duration: 99.943053ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:52.376747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.126553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:52.376789Z","caller":"traceutil/trace.go:171","msg":"trace[1680657090] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:12734; }","duration":"200.194925ms","start":"2026-09-07T11:12:52.176583Z","end":"2026-09-07T11:12:52.376778Z","steps":["trace[1680657090] 'agreement among raft nodes before linearized reading' (duration: 200.068283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:52.726885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.385199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:12:52.726965Z","caller":"traceutil/trace.go:171","msg":"trace[1989185364] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:12735; }","duration":"200.525882ms","start":"2026-09-07T11:12:52.52642Z","end":"2026-09-07T11:12:52.726946Z","steps":["trace[1989185364] 'count revisions from in-memory index tree' (duration: 200.270807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:52.727321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.158738ms","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:5"} {"level":"info","ts":"2026-09-07T11:12:52.727407Z","caller":"traceutil/trace.go:171","msg":"trace[379886041] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:12735; }","duration":"216.331101ms","start":"2026-09-07T11:12:52.511051Z","end":"2026-09-07T11:12:52.727382Z","steps":["trace[379886041] 'count revisions from in-memory index tree' (duration: 216.061664ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:53.132838Z","caller":"traceutil/trace.go:171","msg":"trace[1034184992] transaction","detail":"{read_only:false; response_revision:12736; number_of_response:1; }","duration":"127.075573ms","start":"2026-09-07T11:12:53.005713Z","end":"2026-09-07T11:12:53.132788Z","steps":["trace[1034184992] 'process raft request' (duration: 126.760197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:53.432194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.721521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:12:53.43233Z","caller":"traceutil/trace.go:171","msg":"trace[1017812696] linearizableReadLoop","detail":"{readStateIndex:13180; appliedIndex:13179; }","duration":"209.196127ms","start":"2026-09-07T11:12:53.223086Z","end":"2026-09-07T11:12:53.432282Z","steps":["trace[1017812696] 'read index received' (duration: 12.18251ms)","trace[1017812696] 'applied index is now lower than readState.Index' (duration: 197.012197ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:53.432368Z","caller":"traceutil/trace.go:171","msg":"trace[581593591] transaction","detail":"{read_only:false; response_revision:12737; number_of_response:1; }","duration":"292.343453ms","start":"2026-09-07T11:12:53.139986Z","end":"2026-09-07T11:12:53.43233Z","steps":["trace[581593591] 'process raft request' (duration: 95.397607ms)","trace[581593591] 'compare' (duration: 196.515177ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:53.432533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.684557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-07T11:12:53.432556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.464224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T11:12:53.432591Z","caller":"traceutil/trace.go:171","msg":"trace[188462580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12737; }","duration":"208.764388ms","start":"2026-09-07T11:12:53.22381Z","end":"2026-09-07T11:12:53.432575Z","steps":["trace[188462580] 'agreement among raft nodes before linearized reading' (duration: 208.622365ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:53.432612Z","caller":"traceutil/trace.go:171","msg":"trace[1550091646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12737; }","duration":"209.591756ms","start":"2026-09-07T11:12:53.223004Z","end":"2026-09-07T11:12:53.432596Z","steps":["trace[1550091646] 'agreement among raft nodes before linearized reading' (duration: 209.376502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:53.43264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.941897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T11:12:53.432848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.822149ms","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-09-07T11:12:53.432894Z","caller":"traceutil/trace.go:171","msg":"trace[1617061572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12737; }","duration":"208.929001ms","start":"2026-09-07T11:12:53.223951Z","end":"2026-09-07T11:12:53.43288Z","steps":["trace[1617061572] 'agreement among raft nodes before linearized reading' (duration: 208.801169ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:53.432756Z","caller":"traceutil/trace.go:171","msg":"trace[648009894] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:12737; }","duration":"183.10183ms","start":"2026-09-07T11:12:53.249625Z","end":"2026-09-07T11:12:53.432727Z","steps":["trace[648009894] 'agreement among raft nodes before linearized reading' (duration: 182.868035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:53.432988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.246292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l4m7r\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T11:12:53.433109Z","caller":"traceutil/trace.go:171","msg":"trace[180984059] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l4m7r; range_end:; response_count:1; response_revision:12737; }","duration":"204.409716ms","start":"2026-09-07T11:12:53.228674Z","end":"2026-09-07T11:12:53.433083Z","steps":["trace[180984059] 'agreement among raft nodes before linearized reading' (duration: 204.068398ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:17:03.559218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12283} {"level":"info","ts":"2026-09-07T11:17:03.642218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12283,"took":"80.878282ms","hash":1883898949,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":26677248,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-07T11:17:03.642318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1883898949,"revision":12283,"compact-revision":10310} {"level":"info","ts":"2026-09-07T11:20:40.434888Z","caller":"traceutil/trace.go:171","msg":"trace[1983768402] linearizableReadLoop","detail":"{readStateIndex:16274; appliedIndex:16273; }","duration":"136.785527ms","start":"2026-09-07T11:20:40.298076Z","end":"2026-09-07T11:20:40.434862Z","steps":["trace[1983768402] 'read index received' (duration: 136.507541ms)","trace[1983768402] 'applied index is now lower than readState.Index' (duration: 276.696µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:20:40.434933Z","caller":"traceutil/trace.go:171","msg":"trace[2052951314] transaction","detail":"{read_only:false; response_revision:15726; number_of_response:1; }","duration":"137.526982ms","start":"2026-09-07T11:20:40.297371Z","end":"2026-09-07T11:20:40.434898Z","steps":["trace[2052951314] 'process raft request' (duration: 137.305397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:20:40.435364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.270697ms","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-09-07T11:20:40.435476Z","caller":"traceutil/trace.go:171","msg":"trace[2134424974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15726; }","duration":"137.436012ms","start":"2026-09-07T11:20:40.298016Z","end":"2026-09-07T11:20:40.435452Z","steps":["trace[2134424974] 'agreement among raft nodes before linearized reading' (duration: 136.992961ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:40.5493Z","caller":"traceutil/trace.go:171","msg":"trace[2000627292] transaction","detail":"{read_only:false; response_revision:15728; number_of_response:1; }","duration":"108.687766ms","start":"2026-09-07T11:20:40.44059Z","end":"2026-09-07T11:20:40.549278Z","steps":["trace[2000627292] 'process raft request' (duration: 108.607224ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:40.54936Z","caller":"traceutil/trace.go:171","msg":"trace[628913424] transaction","detail":"{read_only:false; response_revision:15727; number_of_response:1; }","duration":"109.177476ms","start":"2026-09-07T11:20:40.440128Z","end":"2026-09-07T11:20:40.549306Z","steps":["trace[628913424] 'process raft request' (duration: 107.412779ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:40.549461Z","caller":"traceutil/trace.go:171","msg":"trace[1084240391] transaction","detail":"{read_only:false; response_revision:15729; number_of_response:1; }","duration":"108.740297ms","start":"2026-09-07T11:20:40.4407Z","end":"2026-09-07T11:20:40.549441Z","steps":["trace[1084240391] 'process raft request' (duration: 108.547613ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:59.679293Z","caller":"traceutil/trace.go:171","msg":"trace[1826151251] transaction","detail":"{read_only:false; response_revision:15842; number_of_response:1; }","duration":"246.380912ms","start":"2026-09-07T11:20:59.432878Z","end":"2026-09-07T11:20:59.679259Z","steps":["trace[1826151251] 'process raft request' (duration: 246.114466ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:21:10.760667Z","caller":"traceutil/trace.go:171","msg":"trace[214803413] linearizableReadLoop","detail":"{readStateIndex:16571; appliedIndex:16570; }","duration":"122.774887ms","start":"2026-09-07T11:21:10.637862Z","end":"2026-09-07T11:21:10.760637Z","steps":["trace[214803413] 'read index received' (duration: 122.545742ms)","trace[214803413] 'applied index is now lower than readState.Index' (duration: 227.795µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:21:10.760714Z","caller":"traceutil/trace.go:171","msg":"trace[199993330] transaction","detail":"{read_only:false; response_revision:16014; number_of_response:1; }","duration":"133.971277ms","start":"2026-09-07T11:21:10.626718Z","end":"2026-09-07T11:21:10.760689Z","steps":["trace[199993330] 'process raft request' (duration: 133.752203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:21:10.761135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.261727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:21:10.76156Z","caller":"traceutil/trace.go:171","msg":"trace[1777789337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16014; }","duration":"123.739447ms","start":"2026-09-07T11:21:10.637804Z","end":"2026-09-07T11:21:10.761543Z","steps":["trace[1777789337] 'agreement among raft nodes before linearized reading' (duration: 122.963071ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:22:03.565386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14378} {"level":"info","ts":"2026-09-07T11:22:03.644274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14378,"took":"75.341352ms","hash":2387288018,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":29626368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T11:22:03.644332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2387288018,"revision":14378,"compact-revision":12283} {"level":"info","ts":"2026-09-07T11:22:37.220696Z","caller":"traceutil/trace.go:171","msg":"trace[1612218329] linearizableReadLoop","detail":"{readStateIndex:17625; appliedIndex:17624; }","duration":"158.252106ms","start":"2026-09-07T11:22:37.062409Z","end":"2026-09-07T11:22:37.220661Z","steps":["trace[1612218329] 'read index received' (duration: 157.92655ms)","trace[1612218329] 'applied index is now lower than readState.Index' (duration: 324.136µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:22:37.220763Z","caller":"traceutil/trace.go:171","msg":"trace[1641504722] transaction","detail":"{read_only:false; response_revision:17036; number_of_response:1; }","duration":"162.742932ms","start":"2026-09-07T11:22:37.057966Z","end":"2026-09-07T11:22:37.220709Z","steps":["trace[1641504722] 'process raft request' (duration: 162.479117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:37.221696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.262048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769789"} {"level":"info","ts":"2026-09-07T11:22:37.221755Z","caller":"traceutil/trace.go:171","msg":"trace[1627243784] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:17036; }","duration":"159.391141ms","start":"2026-09-07T11:22:37.062348Z","end":"2026-09-07T11:22:37.221739Z","steps":["trace[1627243784] 'agreement among raft nodes before linearized reading' (duration: 158.42986ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:27:03.580719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16737} {"level":"info","ts":"2026-09-07T11:27:03.697932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16737,"took":"113.125471ms","hash":413374053,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":29765632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T11:27:03.698008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":413374053,"revision":16737,"compact-revision":14378} {"level":"info","ts":"2026-09-07T11:28:01.733532Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:28:01.741139Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T11:28:01.741343Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T11:28:37.951941Z","caller":"traceutil/trace.go:171","msg":"trace[142534490] transaction","detail":"{read_only:false; response_revision:19570; number_of_response:1; }","duration":"287.782024ms","start":"2026-09-07T11:28:37.66413Z","end":"2026-09-07T11:28:37.951912Z","steps":["trace[142534490] 'process raft request' (duration: 287.580659ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:37.956917Z","caller":"traceutil/trace.go:171","msg":"trace[1338268522] linearizableReadLoop","detail":"{readStateIndex:20246; appliedIndex:20244; }","duration":"290.631134ms","start":"2026-09-07T11:28:37.666255Z","end":"2026-09-07T11:28:37.956886Z","steps":["trace[1338268522] 'read index received' (duration: 285.419754ms)","trace[1338268522] 'applied index is now lower than readState.Index' (duration: 5.21008ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:37.956979Z","caller":"traceutil/trace.go:171","msg":"trace[300332831] transaction","detail":"{read_only:false; response_revision:19571; number_of_response:1; }","duration":"291.702307ms","start":"2026-09-07T11:28:37.665231Z","end":"2026-09-07T11:28:37.956934Z","steps":["trace[300332831] 'process raft request' (duration: 291.43418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:37.957217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.312295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T11:28:37.957263Z","caller":"traceutil/trace.go:171","msg":"trace[1051114567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19572; }","duration":"280.443268ms","start":"2026-09-07T11:28:37.676809Z","end":"2026-09-07T11:28:37.957252Z","steps":["trace[1051114567] 'agreement among raft nodes before linearized reading' (duration: 280.225534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:37.957442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.156845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-07T11:28:37.95753Z","caller":"traceutil/trace.go:171","msg":"trace[1412191130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19572; }","duration":"291.280677ms","start":"2026-09-07T11:28:37.666231Z","end":"2026-09-07T11:28:37.957511Z","steps":["trace[1412191130] 'agreement among raft nodes before linearized reading' (duration: 290.790987ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:38.467768Z","caller":"traceutil/trace.go:171","msg":"trace[1188473894] transaction","detail":"{read_only:false; response_revision:19575; number_of_response:1; }","duration":"430.295696ms","start":"2026-09-07T11:28:38.03743Z","end":"2026-09-07T11:28:38.467725Z","steps":["trace[1188473894] 'process raft request' (duration: 430.061741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:38.468006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:38.037396Z","time spent":"430.48472ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:28:38.483208Z","caller":"traceutil/trace.go:171","msg":"trace[1735540300] linearizableReadLoop","detail":"{readStateIndex:20251; appliedIndex:20249; }","duration":"403.774045ms","start":"2026-09-07T11:28:38.079382Z","end":"2026-09-07T11:28:38.483156Z","steps":["trace[1735540300] 'read index received' (duration: 388.022263ms)","trace[1735540300] 'applied index is now lower than readState.Index' (duration: 15.750652ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:38.483279Z","caller":"traceutil/trace.go:171","msg":"trace[540545668] transaction","detail":"{read_only:false; response_revision:19576; number_of_response:1; }","duration":"444.659079ms","start":"2026-09-07T11:28:38.038571Z","end":"2026-09-07T11:28:38.48323Z","steps":["trace[540545668] 'process raft request' (duration: 444.438054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:38.483491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:38.038541Z","time spent":"444.833642ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:28:38.483571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.178415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-09-07T11:28:38.483572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.295709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-lpccv\" ","response":"range_response_count:1 size:9794"} {"level":"info","ts":"2026-09-07T11:28:38.483637Z","caller":"traceutil/trace.go:171","msg":"trace[1313416435] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19576; }","duration":"404.325358ms","start":"2026-09-07T11:28:38.079292Z","end":"2026-09-07T11:28:38.483617Z","steps":["trace[1313416435] 'agreement among raft nodes before linearized reading' (duration: 404.050621ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:38.483643Z","caller":"traceutil/trace.go:171","msg":"trace[2024293162] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-lpccv; range_end:; response_count:1; response_revision:19576; }","duration":"257.428552ms","start":"2026-09-07T11:28:38.226198Z","end":"2026-09-07T11:28:38.483626Z","steps":["trace[2024293162] 'agreement among raft nodes before linearized reading' (duration: 257.209387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:38.483683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:38.079227Z","time spent":"404.44358ms","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-09-07T11:29:32.139928Z","caller":"traceutil/trace.go:171","msg":"trace[1479264715] transaction","detail":"{read_only:false; response_revision:20097; number_of_response:1; }","duration":"141.882527ms","start":"2026-09-07T11:29:31.998017Z","end":"2026-09-07T11:29:32.1399Z","steps":["trace[1479264715] 'process raft request' (duration: 141.631662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:29:38.542658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.713701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-07T11:29:38.542723Z","caller":"traceutil/trace.go:171","msg":"trace[1997334243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20161; }","duration":"124.833743ms","start":"2026-09-07T11:29:38.417873Z","end":"2026-09-07T11:29:38.542707Z","steps":["trace[1997334243] 'range keys from in-memory index tree' (duration: 124.501756ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:03.588672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18935} {"level":"info","ts":"2026-09-07T11:32:03.701207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18935,"took":"106.215626ms","hash":4250300672,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":33603584,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-07T11:32:03.701289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4250300672,"revision":18935,"compact-revision":16737} {"level":"info","ts":"2026-09-07T11:36:06.656626Z","caller":"traceutil/trace.go:171","msg":"trace[1087092945] transaction","detail":"{read_only:false; response_revision:22880; number_of_response:1; }","duration":"138.152123ms","start":"2026-09-07T11:36:06.518424Z","end":"2026-09-07T11:36:06.656576Z","steps":["trace[1087092945] 'process raft request' (duration: 111.385546ms)","trace[1087092945] 'compare' (duration: 26.235806ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:06.82059Z","caller":"traceutil/trace.go:171","msg":"trace[635353773] linearizableReadLoop","detail":"{readStateIndex:23664; appliedIndex:23663; }","duration":"141.082887ms","start":"2026-09-07T11:36:06.679463Z","end":"2026-09-07T11:36:06.820546Z","steps":["trace[635353773] 'read index received' (duration: 106.298138ms)","trace[635353773] 'applied index is now lower than readState.Index' (duration: 34.782769ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:06.820813Z","caller":"traceutil/trace.go:171","msg":"trace[644376] transaction","detail":"{read_only:false; response_revision:22881; number_of_response:1; }","duration":"159.407845ms","start":"2026-09-07T11:36:06.661384Z","end":"2026-09-07T11:36:06.820792Z","steps":["trace[644376] 'process raft request' (duration: 124.501113ms)","trace[644376] 'compare' (duration: 34.453383ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:06.82126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.7695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T11:36:06.821356Z","caller":"traceutil/trace.go:171","msg":"trace[767547836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22881; }","duration":"124.926423ms","start":"2026-09-07T11:36:06.69641Z","end":"2026-09-07T11:36:06.821337Z","steps":["trace[767547836] 'agreement among raft nodes before linearized reading' (duration: 124.635667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:06.821353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.845422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T11:36:06.821472Z","caller":"traceutil/trace.go:171","msg":"trace[1864852591] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:22881; }","duration":"142.050437ms","start":"2026-09-07T11:36:06.67939Z","end":"2026-09-07T11:36:06.82144Z","steps":["trace[1864852591] 'agreement among raft nodes before linearized reading' (duration: 141.618068ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:06.98796Z","caller":"traceutil/trace.go:171","msg":"trace[698785534] transaction","detail":"{read_only:false; response_revision:22882; number_of_response:1; }","duration":"162.005559ms","start":"2026-09-07T11:36:06.825912Z","end":"2026-09-07T11:36:06.987917Z","steps":["trace[698785534] 'process raft request' (duration: 123.432571ms)","trace[698785534] 'compare' (duration: 37.901664ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:06.989921Z","caller":"traceutil/trace.go:171","msg":"trace[277139387] transaction","detail":"{read_only:false; response_revision:22883; number_of_response:1; }","duration":"163.570533ms","start":"2026-09-07T11:36:06.826293Z","end":"2026-09-07T11:36:06.989864Z","steps":["trace[277139387] 'process raft request' (duration: 161.217063ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:07.26025Z","caller":"traceutil/trace.go:171","msg":"trace[1380415878] transaction","detail":"{read_only:false; response_revision:22884; number_of_response:1; }","duration":"261.637657ms","start":"2026-09-07T11:36:06.9983Z","end":"2026-09-07T11:36:07.259938Z","steps":["trace[1380415878] 'process raft request' (duration: 181.119857ms)","trace[1380415878] 'compare' (duration: 80.138881ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:07.260756Z","caller":"traceutil/trace.go:171","msg":"trace[1516684368] linearizableReadLoop","detail":"{readStateIndex:23668; appliedIndex:23666; }","duration":"252.822449ms","start":"2026-09-07T11:36:07.007903Z","end":"2026-09-07T11:36:07.260726Z","steps":["trace[1516684368] 'read index received' (duration: 171.532702ms)","trace[1516684368] 'applied index is now lower than readState.Index' (duration: 81.287817ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:07.260968Z","caller":"traceutil/trace.go:171","msg":"trace[1988809504] transaction","detail":"{read_only:false; response_revision:22885; number_of_response:1; }","duration":"259.630444ms","start":"2026-09-07T11:36:07.001319Z","end":"2026-09-07T11:36:07.26095Z","steps":["trace[1988809504] 'process raft request' (duration: 259.257887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:07.261146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.235288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.166\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-07T11:36:07.261228Z","caller":"traceutil/trace.go:171","msg":"trace[68037499] range","detail":"{range_begin:/registry/masterleases/162.253.55.166; range_end:; response_count:1; response_revision:22885; }","duration":"253.364811ms","start":"2026-09-07T11:36:07.007841Z","end":"2026-09-07T11:36:07.261206Z","steps":["trace[68037499] 'agreement among raft nodes before linearized reading' (duration: 253.124226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:07.261506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.682641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T11:36:07.26249Z","caller":"traceutil/trace.go:171","msg":"trace[792631086] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:22885; }","duration":"232.904856ms","start":"2026-09-07T11:36:07.028703Z","end":"2026-09-07T11:36:07.261608Z","steps":["trace[792631086] 'agreement among raft nodes before linearized reading' (duration: 232.62199ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:07.463745Z","caller":"traceutil/trace.go:171","msg":"trace[1124154586] transaction","detail":"{read_only:false; response_revision:22886; number_of_response:1; }","duration":"198.962755ms","start":"2026-09-07T11:36:07.264753Z","end":"2026-09-07T11:36:07.463716Z","steps":["trace[1124154586] 'process raft request' (duration: 109.581527ms)","trace[1124154586] 'compare' (duration: 89.131803ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:07.463925Z","caller":"traceutil/trace.go:171","msg":"trace[232515759] linearizableReadLoop","detail":"{readStateIndex:23671; appliedIndex:23669; }","duration":"153.207963ms","start":"2026-09-07T11:36:07.310684Z","end":"2026-09-07T11:36:07.463892Z","steps":["trace[232515759] 'read index received' (duration: 63.672191ms)","trace[232515759] 'applied index is now lower than readState.Index' (duration: 89.533242ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:07.464129Z","caller":"traceutil/trace.go:171","msg":"trace[302169504] transaction","detail":"{read_only:false; response_revision:22887; number_of_response:1; }","duration":"183.283232ms","start":"2026-09-07T11:36:07.280819Z","end":"2026-09-07T11:36:07.464103Z","steps":["trace[302169504] 'process raft request' (duration: 182.842363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:07.464222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.516649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-07T11:36:07.464262Z","caller":"traceutil/trace.go:171","msg":"trace[1165802481] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22887; }","duration":"153.619621ms","start":"2026-09-07T11:36:07.31063Z","end":"2026-09-07T11:36:07.46425Z","steps":["trace[1165802481] 'agreement among raft nodes before linearized reading' (duration: 153.367636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:07.755746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.642893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:36:07.755891Z","caller":"traceutil/trace.go:171","msg":"trace[2026923928] linearizableReadLoop","detail":"{readStateIndex:23673; appliedIndex:23672; }","duration":"267.576013ms","start":"2026-09-07T11:36:07.48829Z","end":"2026-09-07T11:36:07.755866Z","steps":["trace[2026923928] 'read index received' (duration: 146.546432ms)","trace[2026923928] 'applied index is now lower than readState.Index' (duration: 121.027831ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:07.75623Z","caller":"traceutil/trace.go:171","msg":"trace[937181946] transaction","detail":"{read_only:false; response_revision:22889; number_of_response:1; }","duration":"271.080217ms","start":"2026-09-07T11:36:07.485092Z","end":"2026-09-07T11:36:07.756172Z","steps":["trace[937181946] 'process raft request' (duration: 149.878673ms)","trace[937181946] 'compare' (duration: 120.405237ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:07.756391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.943745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-07T11:36:07.756447Z","caller":"traceutil/trace.go:171","msg":"trace[1375825348] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22889; }","duration":"288.040987ms","start":"2026-09-07T11:36:07.468394Z","end":"2026-09-07T11:36:07.756435Z","steps":["trace[1375825348] 'agreement among raft nodes before linearized reading' (duration: 287.813562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:07.75652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.094911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-07T11:36:07.756623Z","caller":"traceutil/trace.go:171","msg":"trace[2045853377] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:22889; }","duration":"266.211544ms","start":"2026-09-07T11:36:07.490369Z","end":"2026-09-07T11:36:07.756581Z","steps":["trace[2045853377] 'agreement among raft nodes before linearized reading' (duration: 265.916138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:07.756608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.676118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-07T11:36:07.756749Z","caller":"traceutil/trace.go:171","msg":"trace[1362387518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22889; }","duration":"197.876173ms","start":"2026-09-07T11:36:07.558838Z","end":"2026-09-07T11:36:07.756714Z","steps":["trace[1362387518] 'agreement among raft nodes before linearized reading' (duration: 197.481514ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:37:03.59628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21271} {"level":"info","ts":"2026-09-07T11:37:03.724977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21271,"took":"123.719287ms","hash":2651326620,"current-db-size-bytes":55926784,"current-db-size":"56 MB","current-db-size-in-use-bytes":29036544,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-07T11:37:03.725073Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2651326620,"revision":21271,"compact-revision":18935} {"level":"info","ts":"2026-09-07T11:40:36.66053Z","caller":"traceutil/trace.go:171","msg":"trace[1611069262] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"290.072832ms","start":"2026-09-07T11:40:36.370403Z","end":"2026-09-07T11:40:36.660476Z","steps":["trace[1611069262] 'process raft request' (duration: 289.725764ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:41:33.446911Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006941.wal"} {"level":"info","ts":"2026-09-07T11:42:03.605969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23441} {"level":"info","ts":"2026-09-07T11:42:03.761414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23441,"took":"130.256014ms","hash":694047743,"current-db-size-bytes":57143296,"current-db-size":"57 MB","current-db-size-in-use-bytes":43573248,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-07T11:42:03.76154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":694047743,"revision":23441,"compact-revision":21271} {"level":"info","ts":"2026-09-07T11:47:03.61354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26427} {"level":"info","ts":"2026-09-07T11:47:03.762416Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26427,"took":"143.918567ms","hash":3731482500,"current-db-size-bytes":57143296,"current-db-size":"57 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T11:47:03.7625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3731482500,"revision":26427,"compact-revision":23441} {"level":"info","ts":"2026-09-07T11:47:54.911349Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:47:54.919593Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-07T11:47:54.919753Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}