{"level":"warn","ts":"2026-08-22T10:40:41.342348Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:40:41.342451Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.145:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.145:2380","--initial-cluster=instance=https://199.19.213.145:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.145:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.145: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-08-22T10:40:41.342527Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:40:41.342543Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.145:2380"]} {"level":"info","ts":"2026-08-22T10:40:41.342571Z","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-08-22T10:40:41.34325Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"]} {"level":"info","ts":"2026-08-22T10:40:41.343342Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.145:2380"],"listen-peer-urls":["https://199.19.213.145:2380"],"advertise-client-urls":["https://199.19.213.145:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.145: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-08-22T10:40:41.34608Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.449886ms"} {"level":"info","ts":"2026-08-22T10:40:41.351227Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"76a9cbc7029401f7","cluster-id":"ebd7ebb015925037"} {"level":"info","ts":"2026-08-22T10:40:41.351316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:40:41.351343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became follower at term 0"} {"level":"info","ts":"2026-08-22T10:40:41.351363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 76a9cbc7029401f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:40:41.351379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became follower at term 1"} {"level":"info","ts":"2026-08-22T10:40:41.351427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=(8550589423141782007)"} {"level":"warn","ts":"2026-08-22T10:40:41.356259Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:40:41.357597Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:40:41.358923Z","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-08-22T10:40:41.361948Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"76a9cbc7029401f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:40:41.362206Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"76a9cbc7029401f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:40:41.362495Z","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-08-22T10:40:41.363911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=(8550589423141782007)"} {"level":"info","ts":"2026-08-22T10:40:41.36416Z","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-08-22T10:40:41.364275Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ebd7ebb015925037","local-member-id":"76a9cbc7029401f7","added-peer-id":"76a9cbc7029401f7","added-peer-peer-urls":["https://199.19.213.145:2380"]} {"level":"info","ts":"2026-08-22T10:40:41.364358Z","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-08-22T10:40:41.3686Z","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-08-22T10:40:41.368636Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.145:2380"} {"level":"info","ts":"2026-08-22T10:40:41.36872Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.145:2380"} {"level":"info","ts":"2026-08-22T10:40:41.36888Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"76a9cbc7029401f7","initial-advertise-peer-urls":["https://199.19.213.145:2380"],"listen-peer-urls":["https://199.19.213.145:2380"],"advertise-client-urls":["https://199.19.213.145:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:40:41.368916Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:40:42.252554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:40:42.252629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:40:42.252684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 received MsgPreVoteResp from 76a9cbc7029401f7 at term 1"} {"level":"info","ts":"2026-08-22T10:40:42.252705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:40:42.252716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 received MsgVoteResp from 76a9cbc7029401f7 at term 2"} {"level":"info","ts":"2026-08-22T10:40:42.252739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became leader at term 2"} {"level":"info","ts":"2026-08-22T10:40:42.252757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 76a9cbc7029401f7 elected leader 76a9cbc7029401f7 at term 2"} {"level":"info","ts":"2026-08-22T10:40:42.253793Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:42.254718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:40:42.254711Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"76a9cbc7029401f7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.145:2379]}","request-path":"/0/members/76a9cbc7029401f7/attributes","cluster-id":"ebd7ebb015925037","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:40:42.254837Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:40:42.255111Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:40:42.255202Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:40:42.255358Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ebd7ebb015925037","local-member-id":"76a9cbc7029401f7","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:42.255548Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:42.255629Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:42.261026Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.145:2379"} {"level":"info","ts":"2026-08-22T10:40:42.26111Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:40:57.662856Z","caller":"traceutil/trace.go:171","msg":"trace[1093619024] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"179.859393ms","start":"2026-08-22T10:40:57.482955Z","end":"2026-08-22T10:40:57.662814Z","steps":["trace[1093619024] 'process raft request' (duration: 179.640278ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:57.959172Z","caller":"traceutil/trace.go:171","msg":"trace[1630610017] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"129.460579ms","start":"2026-08-22T10:40:57.829679Z","end":"2026-08-22T10:40:57.95914Z","steps":["trace[1630610017] 'process raft request' (duration: 129.318136ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:58.001631Z","caller":"traceutil/trace.go:171","msg":"trace[701646622] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"170.400719ms","start":"2026-08-22T10:40:57.831195Z","end":"2026-08-22T10:40:58.001596Z","steps":["trace[701646622] 'process raft request' (duration: 170.344637ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:58.00173Z","caller":"traceutil/trace.go:171","msg":"trace[1833028441] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"171.932026ms","start":"2026-08-22T10:40:57.829763Z","end":"2026-08-22T10:40:58.001695Z","steps":["trace[1833028441] 'process raft request' (duration: 171.62536ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.378441Z","caller":"traceutil/trace.go:171","msg":"trace[321291341] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"106.344199ms","start":"2026-08-22T10:41:54.272066Z","end":"2026-08-22T10:41:54.37841Z","steps":["trace[321291341] 'read index received' (duration: 106.247458ms)","trace[321291341] 'applied index is now lower than readState.Index' (duration: 95.461µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:54.378528Z","caller":"traceutil/trace.go:171","msg":"trace[733838573] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"107.4183ms","start":"2026-08-22T10:41:54.271084Z","end":"2026-08-22T10:41:54.378503Z","steps":["trace[733838573] 'process raft request' (duration: 107.139115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.378601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.505573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T10:41:54.378688Z","caller":"traceutil/trace.go:171","msg":"trace[520636211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"106.650025ms","start":"2026-08-22T10:41:54.272026Z","end":"2026-08-22T10:41:54.378676Z","steps":["trace[520636211] 'agreement among raft nodes before linearized reading' (duration: 106.505723ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.583119Z","caller":"traceutil/trace.go:171","msg":"trace[1325286395] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"311.003298ms","start":"2026-08-22T10:41:54.272084Z","end":"2026-08-22T10:41:54.583087Z","steps":["trace[1325286395] 'process raft request' (duration: 310.669042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.583983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:54.272064Z","time spent":"311.200731ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:41:54.585265Z","caller":"traceutil/trace.go:171","msg":"trace[528465847] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"232.496184ms","start":"2026-08-22T10:41:54.35275Z","end":"2026-08-22T10:41:54.585246Z","steps":["trace[528465847] 'process raft request' (duration: 230.373614ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.589968Z","caller":"traceutil/trace.go:171","msg":"trace[1764379172] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:684; }","duration":"143.834809ms","start":"2026-08-22T10:41:54.446109Z","end":"2026-08-22T10:41:54.589944Z","steps":["trace[1764379172] 'read index received' (duration: 136.723444ms)","trace[1764379172] 'applied index is now lower than readState.Index' (duration: 7.110165ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:54.59008Z","caller":"traceutil/trace.go:171","msg":"trace[279727805] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"205.78563ms","start":"2026-08-22T10:41:54.384269Z","end":"2026-08-22T10:41:54.590054Z","steps":["trace[279727805] 'process raft request' (duration: 205.551916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.590225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.080223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-22T10:41:54.59022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.652271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kfj48\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-22T10:41:54.590283Z","caller":"traceutil/trace.go:171","msg":"trace[1657133352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"144.219876ms","start":"2026-08-22T10:41:54.446049Z","end":"2026-08-22T10:41:54.590269Z","steps":["trace[1657133352] 'agreement among raft nodes before linearized reading' (duration: 144.045143ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.590303Z","caller":"traceutil/trace.go:171","msg":"trace[1156350825] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kfj48; range_end:; response_count:1; response_revision:657; }","duration":"111.771493ms","start":"2026-08-22T10:41:54.47851Z","end":"2026-08-22T10:41:54.590282Z","steps":["trace[1156350825] 'agreement among raft nodes before linearized reading' (duration: 111.60012ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.897005Z","caller":"traceutil/trace.go:171","msg":"trace[1094731840] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"285.527047ms","start":"2026-08-22T10:41:54.611436Z","end":"2026-08-22T10:41:54.896963Z","steps":["trace[1094731840] 'process raft request' (duration: 277.303551ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:55.061878Z","caller":"traceutil/trace.go:171","msg":"trace[1595757214] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"415.2699ms","start":"2026-08-22T10:41:54.646542Z","end":"2026-08-22T10:41:55.061812Z","steps":["trace[1595757214] 'process raft request' (duration: 407.392801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:55.062169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:54.646524Z","time spent":"415.519094ms","remote":"127.0.0.1:55678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:42:07.182137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.974443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-22T10:42:07.182209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.892463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-22T10:42:07.18226Z","caller":"traceutil/trace.go:171","msg":"trace[808405505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"461.164957ms","start":"2026-08-22T10:42:06.72107Z","end":"2026-08-22T10:42:07.182235Z","steps":["trace[808405505] 'range keys from in-memory index tree' (duration: 460.769109ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:07.182297Z","caller":"traceutil/trace.go:171","msg":"trace[496912238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"460.034746ms","start":"2026-08-22T10:42:06.722245Z","end":"2026-08-22T10:42:07.182279Z","steps":["trace[496912238] 'range keys from in-memory index tree' (duration: 459.7619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:07.18228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.589745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:42:07.182321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:06.721047Z","time spent":"461.263199ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T10:42:07.182344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:06.722234Z","time spent":"460.101887ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T10:42:07.182339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.297662ms","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-08-22T10:42:07.18238Z","caller":"traceutil/trace.go:171","msg":"trace[787892328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"580.743908ms","start":"2026-08-22T10:42:06.601612Z","end":"2026-08-22T10:42:07.182356Z","steps":["trace[787892328] 'range keys from in-memory index tree' (duration: 580.474942ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:07.182417Z","caller":"traceutil/trace.go:171","msg":"trace[453998332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"541.359413ms","start":"2026-08-22T10:42:06.641023Z","end":"2026-08-22T10:42:07.182382Z","steps":["trace[453998332] 'range keys from in-memory index tree' (duration: 541.122049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:07.182431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:06.601582Z","time spent":"580.838189ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:42:07.182464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:06.64098Z","time spent":"541.472155ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:42:08.103187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":141758011490536977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:42:08.587601Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.397877702s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:42:08.589355Z","caller":"traceutil/trace.go:171","msg":"trace[1027079005] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"987.141049ms","start":"2026-08-22T10:42:07.602183Z","end":"2026-08-22T10:42:08.589324Z","steps":["trace[1027079005] 'read index received' (duration: 985.6301ms)","trace[1027079005] 'applied index is now lower than readState.Index' (duration: 1.508919ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:08.58955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.349942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:08.58961Z","caller":"traceutil/trace.go:171","msg":"trace[541277229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"987.494706ms","start":"2026-08-22T10:42:07.602098Z","end":"2026-08-22T10:42:08.589593Z","steps":["trace[541277229] 'agreement among raft nodes before linearized reading' (duration: 987.358653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:08.589664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:07.602075Z","time spent":"987.574698ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:42:08.589582Z","caller":"traceutil/trace.go:171","msg":"trace[413274106] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.399777408s","start":"2026-08-22T10:42:07.189627Z","end":"2026-08-22T10:42:08.589405Z","steps":["trace[413274106] 'process raft request' (duration: 1.39824804s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:08.590146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:07.189609Z","time spent":"1.400298398s","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":802,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:42:08.590635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.361649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-22T10:42:08.590748Z","caller":"traceutil/trace.go:171","msg":"trace[2008709156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"143.513103ms","start":"2026-08-22T10:42:08.44721Z","end":"2026-08-22T10:42:08.590723Z","steps":["trace[2008709156] 'agreement among raft nodes before linearized reading' (duration: 142.888201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:08.590804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.76037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T10:42:08.591034Z","caller":"traceutil/trace.go:171","msg":"trace[943058138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"251.729039ms","start":"2026-08-22T10:42:08.339228Z","end":"2026-08-22T10:42:08.590957Z","steps":["trace[943058138] 'agreement among raft nodes before linearized reading' (duration: 250.324882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:24.257533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.700467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:43:24.257646Z","caller":"traceutil/trace.go:171","msg":"trace[1387339938] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1869; }","duration":"149.902391ms","start":"2026-08-22T10:43:24.10772Z","end":"2026-08-22T10:43:24.257623Z","steps":["trace[1387339938] 'count revisions from in-memory index tree' (duration: 149.573215ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:24.257875Z","caller":"traceutil/trace.go:171","msg":"trace[1116108902] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"148.655186ms","start":"2026-08-22T10:43:24.10916Z","end":"2026-08-22T10:43:24.257815Z","steps":["trace[1116108902] 'process raft request' (duration: 129.536512ms)","trace[1116108902] 'compare' (duration: 18.701317ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:30.141796Z","caller":"traceutil/trace.go:171","msg":"trace[1893692276] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2003; }","duration":"124.203051ms","start":"2026-08-22T10:43:30.01757Z","end":"2026-08-22T10:43:30.141773Z","steps":["trace[1893692276] 'read index received' (duration: 38.866612ms)","trace[1893692276] 'applied index is now lower than readState.Index' (duration: 85.335159ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:30.141882Z","caller":"traceutil/trace.go:171","msg":"trace[53933473] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"130.420609ms","start":"2026-08-22T10:43:30.0114Z","end":"2026-08-22T10:43:30.141821Z","steps":["trace[53933473] 'process raft request' (duration: 94.267039ms)","trace[53933473] 'compare' (duration: 35.973467ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:43:30.142132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.531157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10949"} {"level":"info","ts":"2026-08-22T10:43:30.142245Z","caller":"traceutil/trace.go:171","msg":"trace[1561719100] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1942; }","duration":"124.68607ms","start":"2026-08-22T10:43:30.017536Z","end":"2026-08-22T10:43:30.142222Z","steps":["trace[1561719100] 'agreement among raft nodes before linearized reading' (duration: 124.365054ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:06.461983Z","caller":"traceutil/trace.go:171","msg":"trace[320852237] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"136.655102ms","start":"2026-08-22T10:44:06.3253Z","end":"2026-08-22T10:44:06.461955Z","steps":["trace[320852237] 'process raft request' (duration: 136.499859ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:06.711279Z","caller":"traceutil/trace.go:171","msg":"trace[342825748] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2284; }","duration":"298.626605ms","start":"2026-08-22T10:44:06.412629Z","end":"2026-08-22T10:44:06.711256Z","steps":["trace[342825748] 'read index received' (duration: 49.276112ms)","trace[342825748] 'applied index is now lower than readState.Index' (duration: 249.348732ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:44:06.711492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.322463ms","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":"info","ts":"2026-08-22T10:44:06.711539Z","caller":"traceutil/trace.go:171","msg":"trace[1631942581] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2207; }","duration":"247.467647ms","start":"2026-08-22T10:44:06.464057Z","end":"2026-08-22T10:44:06.711525Z","steps":["trace[1631942581] 'agreement among raft nodes before linearized reading' (duration: 247.344563ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:06.711403Z","caller":"traceutil/trace.go:171","msg":"trace[1153840388] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"306.687581ms","start":"2026-08-22T10:44:06.404653Z","end":"2026-08-22T10:44:06.71134Z","steps":["trace[1153840388] 'process raft request' (duration: 277.501167ms)","trace[1153840388] 'compare' (duration: 28.901949ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:44:06.711713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.91803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-22T10:44:06.711816Z","caller":"traceutil/trace.go:171","msg":"trace[576316199] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2207; }","duration":"178.057334ms","start":"2026-08-22T10:44:06.533735Z","end":"2026-08-22T10:44:06.711793Z","steps":["trace[576316199] 'agreement among raft nodes before linearized reading' (duration: 177.814929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:44:06.711962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:44:06.404629Z","time spent":"307.15854ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:44:06.712186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.548733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-22T10:44:06.712344Z","caller":"traceutil/trace.go:171","msg":"trace[874977588] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2207; }","duration":"299.770367ms","start":"2026-08-22T10:44:06.412549Z","end":"2026-08-22T10:44:06.712319Z","steps":["trace[874977588] 'agreement among raft nodes before linearized reading' (duration: 298.792208ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:45:13.519393Z","caller":"traceutil/trace.go:171","msg":"trace[685495680] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"103.222857ms","start":"2026-08-22T10:45:13.416128Z","end":"2026-08-22T10:45:13.519351Z","steps":["trace[685495680] 'process raft request' (duration: 102.952642ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:45:19.194071Z","caller":"traceutil/trace.go:171","msg":"trace[1404960299] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"240.841027ms","start":"2026-08-22T10:45:18.953198Z","end":"2026-08-22T10:45:19.194039Z","steps":["trace[1404960299] 'process raft request' (duration: 240.644194ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:21.219756Z","caller":"traceutil/trace.go:171","msg":"trace[1126808725] transaction","detail":"{read_only:false; response_revision:3347; number_of_response:1; }","duration":"108.297911ms","start":"2026-08-22T10:46:21.111435Z","end":"2026-08-22T10:46:21.219733Z","steps":["trace[1126808725] 'process raft request' (duration: 108.151428ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:45.576052Z","caller":"traceutil/trace.go:171","msg":"trace[1588811045] transaction","detail":"{read_only:false; response_revision:3541; number_of_response:1; }","duration":"317.65217ms","start":"2026-08-22T10:46:45.258366Z","end":"2026-08-22T10:46:45.576019Z","steps":["trace[1588811045] 'process raft request' (duration: 317.371925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:45.57629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:45.258337Z","time spent":"317.819364ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:46:45.576403Z","caller":"traceutil/trace.go:171","msg":"trace[870920788] linearizableReadLoop","detail":"{readStateIndex:3693; appliedIndex:3691; }","duration":"717.144359ms","start":"2026-08-22T10:46:44.859245Z","end":"2026-08-22T10:46:45.576389Z","steps":["trace[870920788] 'read index received' (duration: 716.319103ms)","trace[870920788] 'applied index is now lower than readState.Index' (duration: 824.476µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:46:45.576703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.853013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-22T10:46:45.576732Z","caller":"traceutil/trace.go:171","msg":"trace[1078322836] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3542; }","duration":"121.912325ms","start":"2026-08-22T10:46:45.454812Z","end":"2026-08-22T10:46:45.576724Z","steps":["trace[1078322836] 'agreement among raft nodes before linearized reading' (duration: 121.820583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:45.576843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.576837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T10:46:45.576962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.890256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:46:45.577022Z","caller":"traceutil/trace.go:171","msg":"trace[1558001816] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:3542; }","duration":"301.957457ms","start":"2026-08-22T10:46:45.275051Z","end":"2026-08-22T10:46:45.577009Z","steps":["trace[1558001816] 'agreement among raft nodes before linearized reading' (duration: 301.858595ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:45.576992Z","caller":"traceutil/trace.go:171","msg":"trace[907214237] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:3542; }","duration":"717.785701ms","start":"2026-08-22T10:46:44.859184Z","end":"2026-08-22T10:46:45.576969Z","steps":["trace[907214237] 'agreement among raft nodes before linearized reading' (duration: 717.533617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:45.577018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.577866ms","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":"info","ts":"2026-08-22T10:46:45.577082Z","caller":"traceutil/trace.go:171","msg":"trace[1286709068] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:3542; }","duration":"307.647847ms","start":"2026-08-22T10:46:45.269423Z","end":"2026-08-22T10:46:45.57707Z","steps":["trace[1286709068] 'agreement among raft nodes before linearized reading' (duration: 307.553245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:45.577063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:45.27498Z","time spent":"302.074629ms","remote":"127.0.0.1:55510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:46:45.577112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:45.269373Z","time spent":"307.730059ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":4,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:46:45.577123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:44.859157Z","time spent":"717.918304ms","remote":"127.0.0.1:55466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":20,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:46:45.577603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.952602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p2z92\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:46:45.57764Z","caller":"traceutil/trace.go:171","msg":"trace[213255320] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p2z92; range_end:; response_count:1; response_revision:3542; }","duration":"556.036984ms","start":"2026-08-22T10:46:45.021593Z","end":"2026-08-22T10:46:45.57763Z","steps":["trace[213255320] 'agreement among raft nodes before linearized reading' (duration: 555.092056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:45.577663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:45.021573Z","time spent":"556.082705ms","remote":"127.0.0.1:55484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-p2z92\" "} {"level":"warn","ts":"2026-08-22T10:46:45.577713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.179534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:46:45.577813Z","caller":"traceutil/trace.go:171","msg":"trace[933315961] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com; range_end:; response_count:0; response_revision:3542; }","duration":"628.191174ms","start":"2026-08-22T10:46:44.949605Z","end":"2026-08-22T10:46:45.577796Z","steps":["trace[933315961] 'agreement among raft nodes before linearized reading' (duration: 627.199254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:45.577921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:44.949577Z","time spent":"628.296576ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com\" "} {"level":"warn","ts":"2026-08-22T10:46:45.578115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.132137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.145\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T10:46:45.578291Z","caller":"traceutil/trace.go:171","msg":"trace[1279087518] range","detail":"{range_begin:/registry/masterleases/199.19.213.145; range_end:; response_count:1; response_revision:3542; }","duration":"191.344022ms","start":"2026-08-22T10:46:45.386923Z","end":"2026-08-22T10:46:45.578267Z","steps":["trace[1279087518] 'agreement among raft nodes before linearized reading' (duration: 191.012475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:45.578425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.780248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-22T10:46:45.578505Z","caller":"traceutil/trace.go:171","msg":"trace[224079647] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3542; }","duration":"144.90946ms","start":"2026-08-22T10:46:45.433579Z","end":"2026-08-22T10:46:45.578488Z","steps":["trace[224079647] 'agreement among raft nodes before linearized reading' (duration: 143.341259ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:35.548227Z","caller":"traceutil/trace.go:171","msg":"trace[1499581158] transaction","detail":"{read_only:false; response_revision:4462; number_of_response:1; }","duration":"115.635852ms","start":"2026-08-22T10:47:35.432522Z","end":"2026-08-22T10:47:35.548158Z","steps":["trace[1499581158] 'process raft request' (duration: 99.751874ms)","trace[1499581158] 'compare' (duration: 15.656674ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:47:39.435791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.404666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c48155e-f58b-46db-9c34-10ac6646f8b2\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-22T10:47:39.435907Z","caller":"traceutil/trace.go:171","msg":"trace[2134262593] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c48155e-f58b-46db-9c34-10ac6646f8b2; range_end:; response_count:1; response_revision:4581; }","duration":"120.616389ms","start":"2026-08-22T10:47:39.315268Z","end":"2026-08-22T10:47:39.435885Z","steps":["trace[2134262593] 'range keys from in-memory index tree' (duration: 120.033329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:39.435777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.109147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-08-22T10:47:39.436553Z","caller":"traceutil/trace.go:171","msg":"trace[1578667776] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4581; }","duration":"124.709008ms","start":"2026-08-22T10:47:39.311554Z","end":"2026-08-22T10:47:39.436263Z","steps":["trace[1578667776] 'range keys from in-memory index tree' (duration: 123.822662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:39.436756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.676687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c48155e-f58b-46db-9c34-10ac6646f8b2\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-22T10:47:39.436821Z","caller":"traceutil/trace.go:171","msg":"trace[1454160609] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c48155e-f58b-46db-9c34-10ac6646f8b2; range_end:; response_count:1; response_revision:4581; }","duration":"192.79819ms","start":"2026-08-22T10:47:39.244009Z","end":"2026-08-22T10:47:39.436807Z","steps":["trace[1454160609] 'range keys from in-memory index tree' (duration: 192.529875ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:41.345742Z","caller":"traceutil/trace.go:171","msg":"trace[1756640964] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"167.458638ms","start":"2026-08-22T10:47:41.178249Z","end":"2026-08-22T10:47:41.345707Z","steps":["trace[1756640964] 'process raft request' (duration: 165.228734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:54.556776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.458285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:47:54.557063Z","caller":"traceutil/trace.go:171","msg":"trace[813298756] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"114.048782ms","start":"2026-08-22T10:47:54.442985Z","end":"2026-08-22T10:47:54.557034Z","steps":["trace[813298756] 'compare' (duration: 106.294271ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:54.55715Z","caller":"traceutil/trace.go:171","msg":"trace[1337365175] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"113.393849ms","start":"2026-08-22T10:47:54.443735Z","end":"2026-08-22T10:47:54.557129Z","steps":["trace[1337365175] 'process raft request' (duration: 113.323957ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:54.557314Z","caller":"traceutil/trace.go:171","msg":"trace[1609997585] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"113.636563ms","start":"2026-08-22T10:47:54.443666Z","end":"2026-08-22T10:47:54.557302Z","steps":["trace[1609997585] 'process raft request' (duration: 113.353848ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:54.557473Z","caller":"traceutil/trace.go:171","msg":"trace[1195095212] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"114.058582ms","start":"2026-08-22T10:47:54.443381Z","end":"2026-08-22T10:47:54.55744Z","steps":["trace[1195095212] 'process raft request' (duration: 113.590743ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:55.452119Z","caller":"traceutil/trace.go:171","msg":"trace[1982386638] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"428.827897ms","start":"2026-08-22T10:47:55.023269Z","end":"2026-08-22T10:47:55.452096Z","steps":["trace[1982386638] 'process raft request' (duration: 428.683714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:55.452272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:47:55.023251Z","time spent":"428.940249ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:47:55.453835Z","caller":"traceutil/trace.go:171","msg":"trace[125017883] linearizableReadLoop","detail":"{readStateIndex:5127; appliedIndex:5125; }","duration":"428.526651ms","start":"2026-08-22T10:47:55.025286Z","end":"2026-08-22T10:47:55.453813Z","steps":["trace[125017883] 'read index received' (duration: 426.719787ms)","trace[125017883] 'applied index is now lower than readState.Index' (duration: 1.805954ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:47:55.453954Z","caller":"traceutil/trace.go:171","msg":"trace[1350773395] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"430.384887ms","start":"2026-08-22T10:47:55.02355Z","end":"2026-08-22T10:47:55.453935Z","steps":["trace[1350773395] 'process raft request' (duration: 430.149912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:55.454105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.808307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-22T10:47:55.454356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.557401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-08-22T10:47:55.454237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.249827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:47:55.454455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:47:55.02354Z","time spent":"430.669293ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:47:55.454364Z","caller":"traceutil/trace.go:171","msg":"trace[1569628863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4913; }","duration":"429.051822ms","start":"2026-08-22T10:47:55.025269Z","end":"2026-08-22T10:47:55.454321Z","steps":["trace[1569628863] 'agreement among raft nodes before linearized reading' (duration: 428.728465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:55.454601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:47:55.025264Z","time spent":"429.318857ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T10:47:55.454453Z","caller":"traceutil/trace.go:171","msg":"trace[1527878804] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4913; }","duration":"247.681144ms","start":"2026-08-22T10:47:55.206743Z","end":"2026-08-22T10:47:55.454424Z","steps":["trace[1527878804] 'agreement among raft nodes before linearized reading' (duration: 247.432129ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:55.45455Z","caller":"traceutil/trace.go:171","msg":"trace[655128423] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4913; }","duration":"326.666687ms","start":"2026-08-22T10:47:55.127865Z","end":"2026-08-22T10:47:55.454532Z","steps":["trace[655128423] 'agreement among raft nodes before linearized reading' (duration: 326.243028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:55.454774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:47:55.127853Z","time spent":"326.902531ms","remote":"127.0.0.1:47642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:48:01.629562Z","caller":"traceutil/trace.go:171","msg":"trace[483652884] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"100.195713ms","start":"2026-08-22T10:48:01.529328Z","end":"2026-08-22T10:48:01.629524Z","steps":["trace[483652884] 'process raft request' (duration: 76.800169ms)","trace[483652884] 'compare' (duration: 23.079048ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:42.284932Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2958} {"level":"info","ts":"2026-08-22T10:50:42.355418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2958,"took":"68.940974ms","hash":377776482,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":36229120,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-22T10:50:42.355503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":377776482,"revision":2958,"compact-revision":-1} {"level":"info","ts":"2026-08-22T10:53:44.848814Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002156.wal"} {"level":"warn","ts":"2026-08-22T10:53:48.619256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.859953ms","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-08-22T10:53:48.619356Z","caller":"traceutil/trace.go:171","msg":"trace[75128973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8202; }","duration":"109.049698ms","start":"2026-08-22T10:53:48.510285Z","end":"2026-08-22T10:53:48.619335Z","steps":["trace[75128973] 'range keys from in-memory index tree' (duration: 108.588808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:48.619492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.673495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-22T10:53:48.619592Z","caller":"traceutil/trace.go:171","msg":"trace[396738268] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8202; }","duration":"275.819148ms","start":"2026-08-22T10:53:48.343749Z","end":"2026-08-22T10:53:48.619568Z","steps":["trace[396738268] 'range keys from in-memory index tree' (duration: 275.293498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:48.619657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.052599ms","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-08-22T10:53:48.619779Z","caller":"traceutil/trace.go:171","msg":"trace[1522035298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8202; }","duration":"116.238064ms","start":"2026-08-22T10:53:48.503511Z","end":"2026-08-22T10:53:48.619749Z","steps":["trace[1522035298] 'range keys from in-memory index tree' (duration: 115.348655ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:48.728472Z","caller":"traceutil/trace.go:171","msg":"trace[428333452] transaction","detail":"{read_only:false; response_revision:8204; number_of_response:1; }","duration":"103.813161ms","start":"2026-08-22T10:53:48.624622Z","end":"2026-08-22T10:53:48.728435Z","steps":["trace[428333452] 'process raft request' (duration: 103.537295ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:48.728829Z","caller":"traceutil/trace.go:171","msg":"trace[220082849] transaction","detail":"{read_only:false; response_revision:8205; number_of_response:1; }","duration":"103.574656ms","start":"2026-08-22T10:53:48.625224Z","end":"2026-08-22T10:53:48.728799Z","steps":["trace[220082849] 'process raft request' (duration: 103.133987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:54:00.765773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.477003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:54:00.765869Z","caller":"traceutil/trace.go:171","msg":"trace[710884318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8281; }","duration":"163.624606ms","start":"2026-08-22T10:54:00.602226Z","end":"2026-08-22T10:54:00.765851Z","steps":["trace[710884318] 'range keys from in-memory index tree' (duration: 163.384691ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:00.941565Z","caller":"traceutil/trace.go:171","msg":"trace[1132408082] linearizableReadLoop","detail":"{readStateIndex:8656; appliedIndex:8655; }","duration":"163.407513ms","start":"2026-08-22T10:54:00.778134Z","end":"2026-08-22T10:54:00.941541Z","steps":["trace[1132408082] 'read index received' (duration: 163.2291ms)","trace[1132408082] 'applied index is now lower than readState.Index' (duration: 177.283µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:54:00.94169Z","caller":"traceutil/trace.go:171","msg":"trace[1632421601] transaction","detail":"{read_only:false; response_revision:8282; number_of_response:1; }","duration":"168.892754ms","start":"2026-08-22T10:54:00.772764Z","end":"2026-08-22T10:54:00.941657Z","steps":["trace[1632421601] 'process raft request' (duration: 168.606939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:54:00.941784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.616777ms","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-08-22T10:54:00.94184Z","caller":"traceutil/trace.go:171","msg":"trace[66517791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8282; }","duration":"163.713349ms","start":"2026-08-22T10:54:00.778113Z","end":"2026-08-22T10:54:00.941827Z","steps":["trace[66517791] 'agreement among raft nodes before linearized reading' (duration: 163.535395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:54:01.616887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.288377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:54:01.617024Z","caller":"traceutil/trace.go:171","msg":"trace[692630339] linearizableReadLoop","detail":"{readStateIndex:8660; appliedIndex:8659; }","duration":"254.469714ms","start":"2026-08-22T10:54:01.362536Z","end":"2026-08-22T10:54:01.617006Z","steps":["trace[692630339] 'read index received' (duration: 70.091µs)","trace[692630339] 'applied index is now lower than readState.Index' (duration: 254.398423ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:54:01.617057Z","caller":"traceutil/trace.go:171","msg":"trace[897699087] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"392.277396ms","start":"2026-08-22T10:54:01.224738Z","end":"2026-08-22T10:54:01.617016Z","steps":["trace[897699087] 'process raft request' (duration: 116.733874ms)","trace[897699087] 'compare' (duration: 275.139844ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:54:01.617222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.693619ms","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-08-22T10:54:01.617245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.598116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T10:54:01.61726Z","caller":"traceutil/trace.go:171","msg":"trace[1068708279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8285; }","duration":"254.77577ms","start":"2026-08-22T10:54:01.362474Z","end":"2026-08-22T10:54:01.61725Z","steps":["trace[1068708279] 'agreement among raft nodes before linearized reading' (duration: 254.627846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:54:01.617269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:54:01.224725Z","time spent":"392.423509ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:54:01.617291Z","caller":"traceutil/trace.go:171","msg":"trace[1722767417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8285; }","duration":"251.682378ms","start":"2026-08-22T10:54:01.365596Z","end":"2026-08-22T10:54:01.617278Z","steps":["trace[1722767417] 'agreement among raft nodes before linearized reading' (duration: 251.556045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:54:01.617617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.644016ms","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-08-22T10:54:01.617732Z","caller":"traceutil/trace.go:171","msg":"trace[1958020876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8285; }","duration":"251.81946ms","start":"2026-08-22T10:54:01.365889Z","end":"2026-08-22T10:54:01.617708Z","steps":["trace[1958020876] 'agreement among raft nodes before linearized reading' (duration: 251.485404ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:01.748322Z","caller":"traceutil/trace.go:171","msg":"trace[1601152209] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"126.472171ms","start":"2026-08-22T10:54:01.621827Z","end":"2026-08-22T10:54:01.748299Z","steps":["trace[1601152209] 'process raft request' (duration: 126.40663ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:01.748324Z","caller":"traceutil/trace.go:171","msg":"trace[1738155036] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"126.876879ms","start":"2026-08-22T10:54:01.621417Z","end":"2026-08-22T10:54:01.748294Z","steps":["trace[1738155036] 'process raft request' (duration: 100.930862ms)","trace[1738155036] 'compare' (duration: 25.804174ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:54:01.748575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.165814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-22T10:54:01.748647Z","caller":"traceutil/trace.go:171","msg":"trace[1520572977] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8288; }","duration":"125.284847ms","start":"2026-08-22T10:54:01.623349Z","end":"2026-08-22T10:54:01.748634Z","steps":["trace[1520572977] 'agreement among raft nodes before linearized reading' (duration: 125.057782ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:01.748305Z","caller":"traceutil/trace.go:171","msg":"trace[660160438] linearizableReadLoop","detail":"{readStateIndex:8663; appliedIndex:8660; }","duration":"124.898849ms","start":"2026-08-22T10:54:01.62338Z","end":"2026-08-22T10:54:01.748279Z","steps":["trace[660160438] 'read index received' (duration: 31.882388ms)","trace[660160438] 'applied index is now lower than readState.Index' (duration: 93.015301ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:54:28.193176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.538087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-22T10:54:28.193244Z","caller":"traceutil/trace.go:171","msg":"trace[1933840075] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8519; }","duration":"134.655479ms","start":"2026-08-22T10:54:28.058576Z","end":"2026-08-22T10:54:28.193231Z","steps":["trace[1933840075] 'range keys from in-memory index tree' (duration: 134.397403ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:28.193417Z","caller":"traceutil/trace.go:171","msg":"trace[177896506] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"108.113759ms","start":"2026-08-22T10:54:28.085277Z","end":"2026-08-22T10:54:28.193391Z","steps":["trace[177896506] 'process raft request' (duration: 95.706246ms)","trace[177896506] 'compare' (duration: 12.095526ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:54:28.348518Z","caller":"traceutil/trace.go:171","msg":"trace[5271843] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"148.54807ms","start":"2026-08-22T10:54:28.199946Z","end":"2026-08-22T10:54:28.348494Z","steps":["trace[5271843] 'process raft request' (duration: 143.958637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:54:41.130299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.733859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T10:54:41.130432Z","caller":"traceutil/trace.go:171","msg":"trace[975621982] linearizableReadLoop","detail":"{readStateIndex:9042; appliedIndex:9041; }","duration":"201.16071ms","start":"2026-08-22T10:54:40.929249Z","end":"2026-08-22T10:54:41.13041Z","steps":["trace[975621982] 'read index received' (duration: 64.240026ms)","trace[975621982] 'applied index is now lower than readState.Index' (duration: 136.919044ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:54:41.130603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.351924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-22T10:54:41.130645Z","caller":"traceutil/trace.go:171","msg":"trace[121167656] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8647; }","duration":"201.399455ms","start":"2026-08-22T10:54:40.92923Z","end":"2026-08-22T10:54:41.13063Z","steps":["trace[121167656] 'agreement among raft nodes before linearized reading' (duration: 201.233392ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:41.977859Z","caller":"traceutil/trace.go:171","msg":"trace[501212216] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"106.389274ms","start":"2026-08-22T10:54:41.87143Z","end":"2026-08-22T10:54:41.97782Z","steps":["trace[501212216] 'process raft request' (duration: 105.543396ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:54:42.591759Z","caller":"traceutil/trace.go:171","msg":"trace[397585848] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"241.163403ms","start":"2026-08-22T10:54:42.350556Z","end":"2026-08-22T10:54:42.59172Z","steps":["trace[397585848] 'process raft request' (duration: 240.721985ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:42.29388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6422} {"level":"info","ts":"2026-08-22T10:55:42.396709Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6422,"took":"100.668947ms","hash":445014935,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":26824704,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-22T10:55:42.396786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":445014935,"revision":6422,"compact-revision":2958} {"level":"info","ts":"2026-08-22T10:55:55.69455Z","caller":"traceutil/trace.go:171","msg":"trace[1245804423] transaction","detail":"{read_only:false; response_revision:9426; number_of_response:1; }","duration":"157.9374ms","start":"2026-08-22T10:55:55.536586Z","end":"2026-08-22T10:55:55.694524Z","steps":["trace[1245804423] 'process raft request' (duration: 157.776717ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:08.458226Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"76a9cbc7029401f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T10:56:08.463138Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T10:56:08.463269Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T11:00:42.300971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9333} {"level":"info","ts":"2026-08-22T11:00:42.389042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9333,"took":"85.275093ms","hash":2958278782,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":28540928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T11:00:42.38912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2958278782,"revision":9333,"compact-revision":6422} {"level":"warn","ts":"2026-08-22T11:05:25.589018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.672671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.145\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-08-22T11:05:25.589179Z","caller":"traceutil/trace.go:171","msg":"trace[454031438] range","detail":"{range_begin:/registry/masterleases/199.19.213.145; range_end:; response_count:1; response_revision:14521; }","duration":"134.898305ms","start":"2026-08-22T11:05:25.454245Z","end":"2026-08-22T11:05:25.589144Z","steps":["trace[454031438] 'range keys from in-memory index tree' (duration: 134.417226ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:05:42.308347Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12172} {"level":"info","ts":"2026-08-22T11:05:42.407279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12172,"took":"95.141491ms","hash":3482781764,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":25776128,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T11:05:42.40735Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3482781764,"revision":12172,"compact-revision":9333} {"level":"warn","ts":"2026-08-22T11:06:03.380748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.109616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T11:06:03.380952Z","caller":"traceutil/trace.go:171","msg":"trace[1679918594] linearizableReadLoop","detail":"{readStateIndex:15487; appliedIndex:15486; }","duration":"421.91454ms","start":"2026-08-22T11:06:02.959003Z","end":"2026-08-22T11:06:03.380917Z","steps":["trace[1679918594] 'read index received' (duration: 39.532819ms)","trace[1679918594] 'applied index is now lower than readState.Index' (duration: 382.379601ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:06:03.381152Z","caller":"traceutil/trace.go:171","msg":"trace[8920095] transaction","detail":"{read_only:false; response_revision:14792; number_of_response:1; }","duration":"476.526904ms","start":"2026-08-22T11:06:02.904606Z","end":"2026-08-22T11:06:03.381132Z","steps":["trace[8920095] 'process raft request' (duration: 93.887727ms)","trace[8920095] 'compare' (duration: 381.80485ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:06:03.381263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:06:02.904591Z","time spent":"476.617135ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:06:03.381409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.792778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T11:06:03.381462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.43079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-22T11:06:03.381553Z","caller":"traceutil/trace.go:171","msg":"trace[1155750152] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:14792; }","duration":"422.565663ms","start":"2026-08-22T11:06:02.958963Z","end":"2026-08-22T11:06:03.381528Z","steps":["trace[1155750152] 'agreement among raft nodes before linearized reading' (duration: 422.287938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:06:03.381608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:06:02.958946Z","time spent":"422.651005ms","remote":"127.0.0.1:55400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T11:06:03.381571Z","caller":"traceutil/trace.go:171","msg":"trace[469925133] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:14792; }","duration":"237.029352ms","start":"2026-08-22T11:06:03.144484Z","end":"2026-08-22T11:06:03.381513Z","steps":["trace[469925133] 'agreement among raft nodes before linearized reading' (duration: 236.661385ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:06:03.641237Z","caller":"traceutil/trace.go:171","msg":"trace[1343776680] linearizableReadLoop","detail":"{readStateIndex:15488; appliedIndex:15487; }","duration":"138.394403ms","start":"2026-08-22T11:06:03.502814Z","end":"2026-08-22T11:06:03.641208Z","steps":["trace[1343776680] 'read index received' (duration: 93.168593ms)","trace[1343776680] 'applied index is now lower than readState.Index' (duration: 45.22442ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:06:03.641295Z","caller":"traceutil/trace.go:171","msg":"trace[304434301] transaction","detail":"{read_only:false; response_revision:14793; number_of_response:1; }","duration":"251.639926ms","start":"2026-08-22T11:06:03.389622Z","end":"2026-08-22T11:06:03.641262Z","steps":["trace[304434301] 'process raft request' (duration: 206.535289ms)","trace[304434301] 'compare' (duration: 44.926534ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:06:03.641552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.71734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p2z92\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T11:06:03.641648Z","caller":"traceutil/trace.go:171","msg":"trace[710464222] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p2z92; range_end:; response_count:1; response_revision:14793; }","duration":"138.871003ms","start":"2026-08-22T11:06:03.502755Z","end":"2026-08-22T11:06:03.641626Z","steps":["trace[710464222] 'agreement among raft nodes before linearized reading' (duration: 138.586877ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:50.279191Z","caller":"traceutil/trace.go:171","msg":"trace[380590296] transaction","detail":"{read_only:false; response_revision:16179; number_of_response:1; }","duration":"126.585413ms","start":"2026-08-22T11:07:50.152574Z","end":"2026-08-22T11:07:50.279159Z","steps":["trace[380590296] 'process raft request' (duration: 126.348179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:07:50.596474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.853869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T11:07:50.59657Z","caller":"traceutil/trace.go:171","msg":"trace[745142045] linearizableReadLoop","detail":"{readStateIndex:16938; appliedIndex:16937; }","duration":"105.554925ms","start":"2026-08-22T11:07:50.491001Z","end":"2026-08-22T11:07:50.596556Z","steps":["trace[745142045] 'read index received' (duration: 33.511µs)","trace[745142045] 'applied index is now lower than readState.Index' (duration: 105.520374ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:07:50.596697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.702448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-qphlc\" ","response":"range_response_count:1 size:9787"} {"level":"info","ts":"2026-08-22T11:07:50.596721Z","caller":"traceutil/trace.go:171","msg":"trace[1440617019] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-qphlc; range_end:; response_count:1; response_revision:16184; }","duration":"105.757219ms","start":"2026-08-22T11:07:50.490957Z","end":"2026-08-22T11:07:50.596714Z","steps":["trace[1440617019] 'agreement among raft nodes before linearized reading' (duration: 105.637707ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:50.596717Z","caller":"traceutil/trace.go:171","msg":"trace[1262866697] transaction","detail":"{read_only:false; response_revision:16184; number_of_response:1; }","duration":"255.35589ms","start":"2026-08-22T11:07:50.341337Z","end":"2026-08-22T11:07:50.596693Z","steps":["trace[1262866697] 'process raft request' (duration: 72.186235ms)","trace[1262866697] 'compare' (duration: 182.658584ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:07:51.142637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.848833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T11:07:51.142755Z","caller":"traceutil/trace.go:171","msg":"trace[1734267219] transaction","detail":"{read_only:false; response_revision:16186; number_of_response:1; }","duration":"250.858352ms","start":"2026-08-22T11:07:50.891879Z","end":"2026-08-22T11:07:51.142738Z","steps":["trace[1734267219] 'process raft request' (duration: 130.775545ms)","trace[1734267219] 'compare' (duration: 119.612557ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:07:51.16099Z","caller":"traceutil/trace.go:171","msg":"trace[368304224] linearizableReadLoop","detail":"{readStateIndex:16941; appliedIndex:16939; }","duration":"249.539275ms","start":"2026-08-22T11:07:50.911428Z","end":"2026-08-22T11:07:51.160967Z","steps":["trace[368304224] 'read index received' (duration: 111.442268ms)","trace[368304224] 'applied index is now lower than readState.Index' (duration: 138.095577ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:07:51.161182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.73331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T11:07:51.161229Z","caller":"traceutil/trace.go:171","msg":"trace[2107787168] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:16186; }","duration":"249.818471ms","start":"2026-08-22T11:07:50.911393Z","end":"2026-08-22T11:07:51.161211Z","steps":["trace[2107787168] 'agreement among raft nodes before linearized reading' (duration: 249.709069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:07:51.161313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.585564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5520"} {"level":"info","ts":"2026-08-22T11:07:51.161467Z","caller":"traceutil/trace.go:171","msg":"trace[921666742] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16186; }","duration":"124.764867ms","start":"2026-08-22T11:07:51.036634Z","end":"2026-08-22T11:07:51.161399Z","steps":["trace[921666742] 'agreement among raft nodes before linearized reading' (duration: 124.481682ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:42.315404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14639} {"level":"info","ts":"2026-08-22T11:10:42.415634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14639,"took":"96.601702ms","hash":1936560491,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":31588352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T11:10:42.415713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1936560491,"revision":14639,"compact-revision":12172} {"level":"info","ts":"2026-08-22T11:13:18.168325Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"76a9cbc7029401f7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:13:18.175257Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T11:13:18.175404Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-08-22T11:13:31.427234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.503458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.427313Z","caller":"traceutil/trace.go:171","msg":"trace[454112709] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:19368; }","duration":"167.65149ms","start":"2026-08-22T11:13:31.259642Z","end":"2026-08-22T11:13:31.427294Z","steps":["trace[454112709] 'range keys from in-memory index tree' (duration: 167.397835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.427424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.60982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.427471Z","caller":"traceutil/trace.go:171","msg":"trace[1593916271] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:19368; }","duration":"167.678681ms","start":"2026-08-22T11:13:31.259762Z","end":"2026-08-22T11:13:31.427441Z","steps":["trace[1593916271] 'range keys from in-memory index tree' (duration: 167.501457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.427562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.748352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.427585Z","caller":"traceutil/trace.go:171","msg":"trace[1501008209] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:0; response_revision:19368; }","duration":"167.827564ms","start":"2026-08-22T11:13:31.259752Z","end":"2026-08-22T11:13:31.42758Z","steps":["trace[1501008209] 'range keys from in-memory index tree' (duration: 167.63788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.42768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.747242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.427703Z","caller":"traceutil/trace.go:171","msg":"trace[746999731] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:19368; }","duration":"167.794213ms","start":"2026-08-22T11:13:31.259903Z","end":"2026-08-22T11:13:31.427697Z","steps":["trace[746999731] 'range keys from in-memory index tree' (duration: 167.610648ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:31.768144Z","caller":"traceutil/trace.go:171","msg":"trace[1236470244] linearizableReadLoop","detail":"{readStateIndex:20275; appliedIndex:20274; }","duration":"315.526226ms","start":"2026-08-22T11:13:31.452587Z","end":"2026-08-22T11:13:31.768114Z","steps":["trace[1236470244] 'read index received' (duration: 315.387303ms)","trace[1236470244] 'applied index is now lower than readState.Index' (duration: 137.733µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:13:31.768232Z","caller":"traceutil/trace.go:171","msg":"trace[196797035] transaction","detail":"{read_only:false; response_revision:19369; number_of_response:1; }","duration":"336.071834ms","start":"2026-08-22T11:13:31.43212Z","end":"2026-08-22T11:13:31.768192Z","steps":["trace[196797035] 'process raft request' (duration: 335.80573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.768366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.743201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.769054Z","caller":"traceutil/trace.go:171","msg":"trace[768745945] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:19369; }","duration":"316.450534ms","start":"2026-08-22T11:13:31.452584Z","end":"2026-08-22T11:13:31.769035Z","steps":["trace[768745945] 'agreement among raft nodes before linearized reading' (duration: 315.695279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.76911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:13:31.452539Z","time spent":"316.556646ms","remote":"127.0.0.1:55534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/senlin-ks-user\" "} {"level":"warn","ts":"2026-08-22T11:13:31.7684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.817643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.769331Z","caller":"traceutil/trace.go:171","msg":"trace[741307382] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:0; response_revision:19369; }","duration":"316.740871ms","start":"2026-08-22T11:13:31.452579Z","end":"2026-08-22T11:13:31.76932Z","steps":["trace[741307382] 'agreement among raft nodes before linearized reading' (duration: 315.805943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.769372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:13:31.452569Z","time spent":"316.792711ms","remote":"127.0.0.1:55534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/senlin-db-sync\" "} {"level":"warn","ts":"2026-08-22T11:13:31.768431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.791062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.769534Z","caller":"traceutil/trace.go:171","msg":"trace[665129867] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:19369; }","duration":"316.952674ms","start":"2026-08-22T11:13:31.45257Z","end":"2026-08-22T11:13:31.769523Z","steps":["trace[665129867] 'agreement among raft nodes before linearized reading' (duration: 315.844774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.769568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:13:31.452548Z","time spent":"317.010505ms","remote":"127.0.0.1:55534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" "} {"level":"warn","ts":"2026-08-22T11:13:31.768609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.251872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-08-22T11:13:31.769823Z","caller":"traceutil/trace.go:171","msg":"trace[413820610] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19369; }","duration":"315.471927ms","start":"2026-08-22T11:13:31.454334Z","end":"2026-08-22T11:13:31.769806Z","steps":["trace[413820610] 'agreement among raft nodes before linearized reading' (duration: 314.174901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.769863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:13:31.454323Z","time spent":"315.528548ms","remote":"127.0.0.1:55400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":670,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T11:13:31.769297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:13:31.432099Z","time spent":"336.852961ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:13:31.768599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.584779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.770438Z","caller":"traceutil/trace.go:171","msg":"trace[521651971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19369; }","duration":"168.401995ms","start":"2026-08-22T11:13:31.601948Z","end":"2026-08-22T11:13:31.77035Z","steps":["trace[521651971] 'agreement among raft nodes before linearized reading' (duration: 166.549259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.768664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.545618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:31.770882Z","caller":"traceutil/trace.go:171","msg":"trace[1095022244] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:19369; }","duration":"317.776931ms","start":"2026-08-22T11:13:31.453061Z","end":"2026-08-22T11:13:31.770799Z","steps":["trace[1095022244] 'agreement among raft nodes before linearized reading' (duration: 315.494887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:31.770984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:13:31.45305Z","time spent":"317.918533ms","remote":"127.0.0.1:55534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" "} {"level":"info","ts":"2026-08-22T11:13:39.747783Z","caller":"traceutil/trace.go:171","msg":"trace[1808954543] linearizableReadLoop","detail":"{readStateIndex:20429; appliedIndex:20428; }","duration":"115.49484ms","start":"2026-08-22T11:13:39.632267Z","end":"2026-08-22T11:13:39.747762Z","steps":["trace[1808954543] 'read index received' (duration: 115.333826ms)","trace[1808954543] 'applied index is now lower than readState.Index' (duration: 159.914µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:13:39.74789Z","caller":"traceutil/trace.go:171","msg":"trace[677956362] transaction","detail":"{read_only:false; response_revision:19514; number_of_response:1; }","duration":"128.994721ms","start":"2026-08-22T11:13:39.618803Z","end":"2026-08-22T11:13:39.747798Z","steps":["trace[677956362] 'process raft request' (duration: 128.811127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:39.747953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.663413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:13:39.748131Z","caller":"traceutil/trace.go:171","msg":"trace[1082765179] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:19514; }","duration":"115.844796ms","start":"2026-08-22T11:13:39.632246Z","end":"2026-08-22T11:13:39.748091Z","steps":["trace[1082765179] 'agreement among raft nodes before linearized reading' (duration: 115.652752ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:15:42.32428Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17788} {"level":"info","ts":"2026-08-22T11:15:42.44648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17788,"took":"119.255141ms","hash":3388437313,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":32866304,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T11:15:42.446572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3388437313,"revision":17788,"compact-revision":14639} {"level":"info","ts":"2026-08-22T11:20:42.331493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20804} {"level":"info","ts":"2026-08-22T11:20:42.446548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20804,"took":"111.987705ms","hash":3850402425,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":32288768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T11:20:42.446636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3850402425,"revision":20804,"compact-revision":17788} {"level":"info","ts":"2026-08-22T11:20:44.078836Z","caller":"traceutil/trace.go:171","msg":"trace[1162623968] transaction","detail":"{read_only:false; response_revision:23369; number_of_response:1; }","duration":"106.942638ms","start":"2026-08-22T11:20:43.971865Z","end":"2026-08-22T11:20:44.078808Z","steps":["trace[1162623968] 'process raft request' (duration: 106.478179ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:59.922706Z","caller":"traceutil/trace.go:171","msg":"trace[961540224] transaction","detail":"{read_only:false; response_revision:23475; number_of_response:1; }","duration":"204.344194ms","start":"2026-08-22T11:20:59.718314Z","end":"2026-08-22T11:20:59.922658Z","steps":["trace[961540224] 'process raft request' (duration: 124.712758ms)","trace[961540224] 'compare' (duration: 79.283569ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:00.385663Z","caller":"traceutil/trace.go:171","msg":"trace[1005523189] linearizableReadLoop","detail":"{readStateIndex:24583; appliedIndex:24582; }","duration":"139.571354ms","start":"2026-08-22T11:21:00.246063Z","end":"2026-08-22T11:21:00.385634Z","steps":["trace[1005523189] 'read index received' (duration: 139.347449ms)","trace[1005523189] 'applied index is now lower than readState.Index' (duration: 222.935µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:00.385698Z","caller":"traceutil/trace.go:171","msg":"trace[132810112] transaction","detail":"{read_only:false; response_revision:23476; number_of_response:1; }","duration":"199.086652ms","start":"2026-08-22T11:21:00.186578Z","end":"2026-08-22T11:21:00.385665Z","steps":["trace[132810112] 'process raft request' (duration: 198.893689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:00.386156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.286308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T11:21:00.386208Z","caller":"traceutil/trace.go:171","msg":"trace[93912919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23476; }","duration":"127.4157ms","start":"2026-08-22T11:21:00.25878Z","end":"2026-08-22T11:21:00.386195Z","steps":["trace[93912919] 'agreement among raft nodes before linearized reading' (duration: 127.015172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:00.386189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.105393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-08-22T11:21:00.386268Z","caller":"traceutil/trace.go:171","msg":"trace[1987259687] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23476; }","duration":"140.244926ms","start":"2026-08-22T11:21:00.246005Z","end":"2026-08-22T11:21:00.38625Z","steps":["trace[1987259687] 'agreement among raft nodes before linearized reading' (duration: 139.749677ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:00.506062Z","caller":"traceutil/trace.go:171","msg":"trace[1441719381] transaction","detail":"{read_only:false; response_revision:23478; number_of_response:1; }","duration":"110.766071ms","start":"2026-08-22T11:21:00.395258Z","end":"2026-08-22T11:21:00.506024Z","steps":["trace[1441719381] 'process raft request' (duration: 98.292622ms)","trace[1441719381] 'compare' (duration: 12.194954ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:00.811506Z","caller":"traceutil/trace.go:171","msg":"trace[1946739345] linearizableReadLoop","detail":"{readStateIndex:24588; appliedIndex:24586; }","duration":"256.539963ms","start":"2026-08-22T11:21:00.554936Z","end":"2026-08-22T11:21:00.811476Z","steps":["trace[1946739345] 'read index received' (duration: 248.916217ms)","trace[1946739345] 'applied index is now lower than readState.Index' (duration: 7.621806ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:00.811642Z","caller":"traceutil/trace.go:171","msg":"trace[684120684] transaction","detail":"{read_only:false; response_revision:23480; number_of_response:1; }","duration":"296.306395ms","start":"2026-08-22T11:21:00.515286Z","end":"2026-08-22T11:21:00.811592Z","steps":["trace[684120684] 'process raft request' (duration: 288.527247ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:00.811807Z","caller":"traceutil/trace.go:171","msg":"trace[1284273216] transaction","detail":"{read_only:false; response_revision:23481; number_of_response:1; }","duration":"296.467868ms","start":"2026-08-22T11:21:00.515294Z","end":"2026-08-22T11:21:00.811762Z","steps":["trace[1284273216] 'process raft request' (duration: 296.108192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:00.812088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.922907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11501"} {"level":"info","ts":"2026-08-22T11:21:00.812176Z","caller":"traceutil/trace.go:171","msg":"trace[733333175] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23481; }","duration":"296.04941ms","start":"2026-08-22T11:21:00.51611Z","end":"2026-08-22T11:21:00.81216Z","steps":["trace[733333175] 'agreement among raft nodes before linearized reading' (duration: 295.635472ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:00.822949Z","caller":"traceutil/trace.go:171","msg":"trace[1828599270] transaction","detail":"{read_only:false; response_revision:23482; number_of_response:1; }","duration":"262.968186ms","start":"2026-08-22T11:21:00.559952Z","end":"2026-08-22T11:21:00.82292Z","steps":["trace[1828599270] 'process raft request' (duration: 262.863664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:01.074212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.744924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:21:01.074327Z","caller":"traceutil/trace.go:171","msg":"trace[1664915657] transaction","detail":"{read_only:false; response_revision:23483; number_of_response:1; }","duration":"243.986894ms","start":"2026-08-22T11:21:00.830319Z","end":"2026-08-22T11:21:01.074306Z","steps":["trace[1664915657] 'process raft request' (duration: 125.061846ms)","trace[1664915657] 'compare' (duration: 118.598221ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:01.091439Z","caller":"traceutil/trace.go:171","msg":"trace[1809809246] transaction","detail":"{read_only:false; response_revision:23484; number_of_response:1; }","duration":"217.079928ms","start":"2026-08-22T11:21:00.874247Z","end":"2026-08-22T11:21:01.091327Z","steps":["trace[1809809246] 'process raft request' (duration: 216.869054ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:24:26.887849Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a00.wal"} {"level":"info","ts":"2026-08-22T11:25:42.338576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23364} {"level":"info","ts":"2026-08-22T11:25:42.448504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23364,"took":"104.003301ms","hash":2154224022,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":44920832,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-22T11:25:42.448583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2154224022,"revision":23364,"compact-revision":20804} {"level":"info","ts":"2026-08-22T11:28:05.835932Z","caller":"traceutil/trace.go:171","msg":"trace[1023716762] linearizableReadLoop","detail":"{readStateIndex:29867; appliedIndex:29866; }","duration":"127.881933ms","start":"2026-08-22T11:28:05.708004Z","end":"2026-08-22T11:28:05.835886Z","steps":["trace[1023716762] 'read index received' (duration: 127.70661ms)","trace[1023716762] 'applied index is now lower than readState.Index' (duration: 173.663µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:05.83617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.155728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-08-22T11:28:05.836221Z","caller":"traceutil/trace.go:171","msg":"trace[1431562904] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28553; }","duration":"128.23304ms","start":"2026-08-22T11:28:05.70796Z","end":"2026-08-22T11:28:05.836193Z","steps":["trace[1431562904] 'agreement among raft nodes before linearized reading' (duration: 128.089787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:05.836409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.426429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:28:05.836499Z","caller":"traceutil/trace.go:171","msg":"trace[1154873581] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:28553; }","duration":"122.591442ms","start":"2026-08-22T11:28:05.713885Z","end":"2026-08-22T11:28:05.836477Z","steps":["trace[1154873581] 'agreement among raft nodes before linearized reading' (duration: 122.397018ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:06.087566Z","caller":"traceutil/trace.go:171","msg":"trace[900787896] transaction","detail":"{read_only:false; response_revision:28556; number_of_response:1; }","duration":"166.008693ms","start":"2026-08-22T11:28:05.921511Z","end":"2026-08-22T11:28:06.08752Z","steps":["trace[900787896] 'process raft request' (duration: 118.101967ms)","trace[900787896] 'compare' (duration: 47.540318ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:06.780625Z","caller":"traceutil/trace.go:171","msg":"trace[1736793865] linearizableReadLoop","detail":"{readStateIndex:29873; appliedIndex:29872; }","duration":"177.91916ms","start":"2026-08-22T11:28:06.602684Z","end":"2026-08-22T11:28:06.780603Z","steps":["trace[1736793865] 'read index received' (duration: 177.90056ms)","trace[1736793865] 'applied index is now lower than readState.Index' (duration: 17.87µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:06.780723Z","caller":"traceutil/trace.go:171","msg":"trace[1616573986] transaction","detail":"{read_only:false; response_revision:28559; number_of_response:1; }","duration":"266.004714ms","start":"2026-08-22T11:28:06.514709Z","end":"2026-08-22T11:28:06.780714Z","steps":["trace[1616573986] 'process raft request' (duration: 265.745009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:06.780865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.171605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:06.780939Z","caller":"traceutil/trace.go:171","msg":"trace[601855307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28559; }","duration":"178.289707ms","start":"2026-08-22T11:28:06.602633Z","end":"2026-08-22T11:28:06.780923Z","steps":["trace[601855307] 'agreement among raft nodes before linearized reading' (duration: 178.169895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:06.781007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.845987ms","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-08-22T11:28:06.781063Z","caller":"traceutil/trace.go:171","msg":"trace[1468542905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28559; }","duration":"145.99646ms","start":"2026-08-22T11:28:06.63505Z","end":"2026-08-22T11:28:06.781047Z","steps":["trace[1468542905] 'agreement among raft nodes before linearized reading' (duration: 145.808357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:06.781038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.920156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T11:28:06.781124Z","caller":"traceutil/trace.go:171","msg":"trace[1955647059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28559; }","duration":"160.040438ms","start":"2026-08-22T11:28:06.62106Z","end":"2026-08-22T11:28:06.781101Z","steps":["trace[1955647059] 'agreement among raft nodes before linearized reading' (duration: 159.782943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:06.781732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.614781ms","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-08-22T11:28:06.78198Z","caller":"traceutil/trace.go:171","msg":"trace[1770066860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28559; }","duration":"146.918878ms","start":"2026-08-22T11:28:06.635011Z","end":"2026-08-22T11:28:06.781929Z","steps":["trace[1770066860] 'agreement among raft nodes before linearized reading' (duration: 145.95084ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:07.134921Z","caller":"traceutil/trace.go:171","msg":"trace[1934924277] transaction","detail":"{read_only:false; response_revision:28563; number_of_response:1; }","duration":"124.078002ms","start":"2026-08-22T11:28:07.010809Z","end":"2026-08-22T11:28:07.134887Z","steps":["trace[1934924277] 'process raft request' (duration: 123.48874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:07.358066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.371011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:07.358502Z","caller":"traceutil/trace.go:171","msg":"trace[673319692] transaction","detail":"{read_only:false; response_revision:28565; number_of_response:1; }","duration":"218.802971ms","start":"2026-08-22T11:28:07.139677Z","end":"2026-08-22T11:28:07.358479Z","steps":["trace[673319692] 'process raft request' (duration: 85.886491ms)","trace[673319692] 'compare' (duration: 132.139066ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:07.358537Z","caller":"traceutil/trace.go:171","msg":"trace[209365650] linearizableReadLoop","detail":"{readStateIndex:29879; appliedIndex:29878; }","duration":"183.981825ms","start":"2026-08-22T11:28:07.174522Z","end":"2026-08-22T11:28:07.358504Z","steps":["trace[209365650] 'read index received' (duration: 50.969953ms)","trace[209365650] 'applied index is now lower than readState.Index' (duration: 133.009402ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:07.358732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.18696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:07.358775Z","caller":"traceutil/trace.go:171","msg":"trace[272886116] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:28566; }","duration":"184.280712ms","start":"2026-08-22T11:28:07.174483Z","end":"2026-08-22T11:28:07.358764Z","steps":["trace[272886116] 'agreement among raft nodes before linearized reading' (duration: 184.1764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:07.358842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.563986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11501"} {"level":"info","ts":"2026-08-22T11:28:07.358886Z","caller":"traceutil/trace.go:171","msg":"trace[689818890] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28566; }","duration":"163.633207ms","start":"2026-08-22T11:28:07.195241Z","end":"2026-08-22T11:28:07.358875Z","steps":["trace[689818890] 'agreement among raft nodes before linearized reading' (duration: 163.489205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:07.35884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.931216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:28:07.35902Z","caller":"traceutil/trace.go:171","msg":"trace[1852152550] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:28566; }","duration":"171.149111ms","start":"2026-08-22T11:28:07.187813Z","end":"2026-08-22T11:28:07.358962Z","steps":["trace[1852152550] 'agreement among raft nodes before linearized reading' (duration: 170.900626ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:10.216821Z","caller":"traceutil/trace.go:171","msg":"trace[853236542] linearizableReadLoop","detail":"{readStateIndex:29905; appliedIndex:29904; }","duration":"106.280811ms","start":"2026-08-22T11:28:10.110515Z","end":"2026-08-22T11:28:10.216796Z","steps":["trace[853236542] 'read index received' (duration: 106.027757ms)","trace[853236542] 'applied index is now lower than readState.Index' (duration: 245.804µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:10.216893Z","caller":"traceutil/trace.go:171","msg":"trace[673542712] transaction","detail":"{read_only:false; response_revision:28590; number_of_response:1; }","duration":"114.634911ms","start":"2026-08-22T11:28:10.10224Z","end":"2026-08-22T11:28:10.216875Z","steps":["trace[673542712] 'process raft request' (duration: 114.386196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:10.21708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.540066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-08-22T11:28:10.217125Z","caller":"traceutil/trace.go:171","msg":"trace[663537118] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28590; }","duration":"106.617487ms","start":"2026-08-22T11:28:10.110494Z","end":"2026-08-22T11:28:10.217112Z","steps":["trace[663537118] 'agreement among raft nodes before linearized reading' (duration: 106.441404ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:10.38156Z","caller":"traceutil/trace.go:171","msg":"trace[1256187992] linearizableReadLoop","detail":"{readStateIndex:29907; appliedIndex:29906; }","duration":"123.592373ms","start":"2026-08-22T11:28:10.257942Z","end":"2026-08-22T11:28:10.381534Z","steps":["trace[1256187992] 'read index received' (duration: 123.369398ms)","trace[1256187992] 'applied index is now lower than readState.Index' (duration: 221.815µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:10.381647Z","caller":"traceutil/trace.go:171","msg":"trace[98776835] transaction","detail":"{read_only:false; response_revision:28592; number_of_response:1; }","duration":"132.439102ms","start":"2026-08-22T11:28:10.249139Z","end":"2026-08-22T11:28:10.381578Z","steps":["trace[98776835] 'process raft request' (duration: 132.243657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:10.38188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.920429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-22T11:28:10.38195Z","caller":"traceutil/trace.go:171","msg":"trace[2008468135] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:28592; }","duration":"124.038591ms","start":"2026-08-22T11:28:10.257881Z","end":"2026-08-22T11:28:10.38192Z","steps":["trace[2008468135] 'agreement among raft nodes before linearized reading' (duration: 123.757256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:10.984618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.396379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-22T11:28:10.984678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.073763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T11:28:10.984763Z","caller":"traceutil/trace.go:171","msg":"trace[78301355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28593; }","duration":"122.615183ms","start":"2026-08-22T11:28:10.862119Z","end":"2026-08-22T11:28:10.984734Z","steps":["trace[78301355] 'range keys from in-memory index tree' (duration: 121.786717ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:10.984809Z","caller":"traceutil/trace.go:171","msg":"trace[958508934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28593; }","duration":"122.241596ms","start":"2026-08-22T11:28:10.862534Z","end":"2026-08-22T11:28:10.984776Z","steps":["trace[958508934] 'range keys from in-memory index tree' (duration: 121.830168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:10.984855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.340288ms","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-08-22T11:28:10.984966Z","caller":"traceutil/trace.go:171","msg":"trace[1105350984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28593; }","duration":"122.497811ms","start":"2026-08-22T11:28:10.862442Z","end":"2026-08-22T11:28:10.98494Z","steps":["trace[1105350984] 'range keys from in-memory index tree' (duration: 122.122464ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:11.223931Z","caller":"traceutil/trace.go:171","msg":"trace[1889849406] transaction","detail":"{read_only:false; response_revision:28595; number_of_response:1; }","duration":"232.947091ms","start":"2026-08-22T11:28:10.990935Z","end":"2026-08-22T11:28:11.223882Z","steps":["trace[1889849406] 'process raft request' (duration: 196.857331ms)","trace[1889849406] 'compare' (duration: 35.852746ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:11.224031Z","caller":"traceutil/trace.go:171","msg":"trace[1179576996] transaction","detail":"{read_only:false; response_revision:28596; number_of_response:1; }","duration":"232.38067ms","start":"2026-08-22T11:28:10.991617Z","end":"2026-08-22T11:28:11.223998Z","steps":["trace[1179576996] 'process raft request' (duration: 232.194467ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:11.472629Z","caller":"traceutil/trace.go:171","msg":"trace[570082521] transaction","detail":"{read_only:false; response_revision:28597; number_of_response:1; }","duration":"309.012835ms","start":"2026-08-22T11:28:11.163591Z","end":"2026-08-22T11:28:11.472604Z","steps":["trace[570082521] 'process raft request' (duration: 308.849022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:11.472814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:11.163573Z","time spent":"309.141988ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:28:11.518353Z","caller":"traceutil/trace.go:171","msg":"trace[1520341530] linearizableReadLoop","detail":"{readStateIndex:29914; appliedIndex:29912; }","duration":"204.373575ms","start":"2026-08-22T11:28:11.313952Z","end":"2026-08-22T11:28:11.518325Z","steps":["trace[1520341530] 'read index received' (duration: 158.488369ms)","trace[1520341530] 'applied index is now lower than readState.Index' (duration: 45.883726ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:11.518386Z","caller":"traceutil/trace.go:171","msg":"trace[1017601066] transaction","detail":"{read_only:false; response_revision:28598; number_of_response:1; }","duration":"289.183236ms","start":"2026-08-22T11:28:11.229168Z","end":"2026-08-22T11:28:11.518351Z","steps":["trace[1017601066] 'process raft request' (duration: 288.922551ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:11.518441Z","caller":"traceutil/trace.go:171","msg":"trace[169127597] transaction","detail":"{read_only:false; response_revision:28599; number_of_response:1; }","duration":"147.994657ms","start":"2026-08-22T11:28:11.370387Z","end":"2026-08-22T11:28:11.518382Z","steps":["trace[169127597] 'process raft request' (duration: 147.882405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:11.518683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.380308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:28:11.518732Z","caller":"traceutil/trace.go:171","msg":"trace[2042781584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28599; }","duration":"146.4978ms","start":"2026-08-22T11:28:11.372221Z","end":"2026-08-22T11:28:11.518719Z","steps":["trace[2042781584] 'agreement among raft nodes before linearized reading' (duration: 146.300457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:11.518735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.750614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-22T11:28:11.518876Z","caller":"traceutil/trace.go:171","msg":"trace[1842798889] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:28599; }","duration":"204.951617ms","start":"2026-08-22T11:28:11.313895Z","end":"2026-08-22T11:28:11.518847Z","steps":["trace[1842798889] 'agreement among raft nodes before linearized reading' (duration: 204.58603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:11.740036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.270066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:11.740319Z","caller":"traceutil/trace.go:171","msg":"trace[910712155] linearizableReadLoop","detail":"{readStateIndex:29918; appliedIndex:29915; }","duration":"209.130267ms","start":"2026-08-22T11:28:11.53117Z","end":"2026-08-22T11:28:11.7403Z","steps":["trace[910712155] 'read index received' (duration: 18.871781ms)","trace[910712155] 'applied index is now lower than readState.Index' (duration: 190.257376ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:11.740531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.357411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-08-22T11:28:11.740567Z","caller":"traceutil/trace.go:171","msg":"trace[1810804615] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28602; }","duration":"209.414541ms","start":"2026-08-22T11:28:11.531137Z","end":"2026-08-22T11:28:11.740551Z","steps":["trace[1810804615] 'agreement among raft nodes before linearized reading' (duration: 209.219618ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:11.740669Z","caller":"traceutil/trace.go:171","msg":"trace[159491718] transaction","detail":"{read_only:false; response_revision:28601; number_of_response:1; }","duration":"215.987387ms","start":"2026-08-22T11:28:11.524615Z","end":"2026-08-22T11:28:11.740602Z","steps":["trace[159491718] 'process raft request' (duration: 81.015358ms)","trace[159491718] 'compare' (duration: 134.051892ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:11.740725Z","caller":"traceutil/trace.go:171","msg":"trace[1303265207] transaction","detail":"{read_only:false; response_revision:28602; number_of_response:1; }","duration":"215.167852ms","start":"2026-08-22T11:28:11.525506Z","end":"2026-08-22T11:28:11.740674Z","steps":["trace[1303265207] 'process raft request' (duration: 214.676153ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:11.995573Z","caller":"traceutil/trace.go:171","msg":"trace[1742669438] transaction","detail":"{read_only:false; response_revision:28603; number_of_response:1; }","duration":"245.687565ms","start":"2026-08-22T11:28:11.749844Z","end":"2026-08-22T11:28:11.995532Z","steps":["trace[1742669438] 'process raft request' (duration: 237.090801ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:12.870025Z","caller":"traceutil/trace.go:171","msg":"trace[661890526] linearizableReadLoop","detail":"{readStateIndex:29924; appliedIndex:29923; }","duration":"203.391057ms","start":"2026-08-22T11:28:12.666612Z","end":"2026-08-22T11:28:12.870003Z","steps":["trace[661890526] 'read index received' (duration: 203.226344ms)","trace[661890526] 'applied index is now lower than readState.Index' (duration: 162.893µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:12.870069Z","caller":"traceutil/trace.go:171","msg":"trace[2097731594] transaction","detail":"{read_only:false; response_revision:28608; number_of_response:1; }","duration":"254.194798ms","start":"2026-08-22T11:28:12.615842Z","end":"2026-08-22T11:28:12.870036Z","steps":["trace[2097731594] 'process raft request' (duration: 254.005595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:12.870281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.649191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-22T11:28:12.870316Z","caller":"traceutil/trace.go:171","msg":"trace[588665824] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:28608; }","duration":"203.743763ms","start":"2026-08-22T11:28:12.666563Z","end":"2026-08-22T11:28:12.870307Z","steps":["trace[588665824] 'agreement among raft nodes before linearized reading' (duration: 203.53234ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.159427Z","caller":"traceutil/trace.go:171","msg":"trace[2068621300] linearizableReadLoop","detail":"{readStateIndex:29927; appliedIndex:29925; }","duration":"155.90198ms","start":"2026-08-22T11:28:13.003502Z","end":"2026-08-22T11:28:13.159403Z","steps":["trace[2068621300] 'read index received' (duration: 155.652014ms)","trace[2068621300] 'applied index is now lower than readState.Index' (duration: 248.826µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:13.159514Z","caller":"traceutil/trace.go:171","msg":"trace[1754984211] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28609; }","duration":"280.869928ms","start":"2026-08-22T11:28:12.878621Z","end":"2026-08-22T11:28:13.159491Z","steps":["trace[1754984211] 'process raft request' (duration: 280.602022ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.159593Z","caller":"traceutil/trace.go:171","msg":"trace[1590529922] transaction","detail":"{read_only:false; response_revision:28610; number_of_response:1; }","duration":"260.124581ms","start":"2026-08-22T11:28:12.899456Z","end":"2026-08-22T11:28:13.15958Z","steps":["trace[1590529922] 'process raft request' (duration: 259.848425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:13.159855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.332578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:28:13.159902Z","caller":"traceutil/trace.go:171","msg":"trace[708253494] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:28610; }","duration":"156.477941ms","start":"2026-08-22T11:28:13.00341Z","end":"2026-08-22T11:28:13.159888Z","steps":["trace[708253494] 'agreement among raft nodes before linearized reading' (duration: 156.299297ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.53556Z","caller":"traceutil/trace.go:171","msg":"trace[748864033] linearizableReadLoop","detail":"{readStateIndex:29929; appliedIndex:29928; }","duration":"306.390566ms","start":"2026-08-22T11:28:13.229131Z","end":"2026-08-22T11:28:13.535522Z","steps":["trace[748864033] 'read index received' (duration: 306.244284ms)","trace[748864033] 'applied index is now lower than readState.Index' (duration: 144.682µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:13.535604Z","caller":"traceutil/trace.go:171","msg":"trace[872742953] transaction","detail":"{read_only:false; response_revision:28612; number_of_response:1; }","duration":"364.642448ms","start":"2026-08-22T11:28:13.170924Z","end":"2026-08-22T11:28:13.535566Z","steps":["trace[872742953] 'process raft request' (duration: 364.400904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:13.535759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.61856ms","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-08-22T11:28:13.535795Z","caller":"traceutil/trace.go:171","msg":"trace[1218117263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28612; }","duration":"306.706482ms","start":"2026-08-22T11:28:13.229077Z","end":"2026-08-22T11:28:13.535784Z","steps":["trace[1218117263] 'agreement among raft nodes before linearized reading' (duration: 306.57584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:13.535825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:13.22906Z","time spent":"306.757223ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T11:28:13.535831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:13.170903Z","time spent":"364.836372ms","remote":"127.0.0.1:55802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:28:13.813353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.824043ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:13.813476Z","caller":"traceutil/trace.go:171","msg":"trace[110458424] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:28612; }","duration":"195.957595ms","start":"2026-08-22T11:28:13.617503Z","end":"2026-08-22T11:28:13.81346Z","steps":["trace[110458424] 'range keys from in-memory index tree' (duration: 195.806682ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.813874Z","caller":"traceutil/trace.go:171","msg":"trace[2028706165] linearizableReadLoop","detail":"{readStateIndex:29931; appliedIndex:29929; }","duration":"278.225677ms","start":"2026-08-22T11:28:13.535625Z","end":"2026-08-22T11:28:13.81385Z","steps":["trace[2028706165] 'read index received' (duration: 269.45782ms)","trace[2028706165] 'applied index is now lower than readState.Index' (duration: 8.766047ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:13.813945Z","caller":"traceutil/trace.go:171","msg":"trace[726752378] transaction","detail":"{read_only:false; response_revision:28614; number_of_response:1; }","duration":"289.608365ms","start":"2026-08-22T11:28:13.524264Z","end":"2026-08-22T11:28:13.813872Z","steps":["trace[726752378] 'process raft request' (duration: 289.487472ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.814008Z","caller":"traceutil/trace.go:171","msg":"trace[1170727134] transaction","detail":"{read_only:false; response_revision:28613; number_of_response:1; }","duration":"335.435901ms","start":"2026-08-22T11:28:13.478557Z","end":"2026-08-22T11:28:13.813993Z","steps":["trace[1170727134] 'process raft request' (duration: 326.509511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:13.814116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:13.478533Z","time spent":"335.510172ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:28:13.814174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.345547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:28:13.814251Z","caller":"traceutil/trace.go:171","msg":"trace[557189765] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:28614; }","duration":"314.473719ms","start":"2026-08-22T11:28:13.499758Z","end":"2026-08-22T11:28:13.814232Z","steps":["trace[557189765] 'agreement among raft nodes before linearized reading' (duration: 314.307906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:13.814292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:13.499745Z","time spent":"314.53992ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":5,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:28:13.814249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.386995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:28:13.814281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.121292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-22T11:28:13.814316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.414588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T11:28:13.814568Z","caller":"traceutil/trace.go:171","msg":"trace[1577757375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28614; }","duration":"583.683244ms","start":"2026-08-22T11:28:13.230852Z","end":"2026-08-22T11:28:13.814535Z","steps":["trace[1577757375] 'agreement among raft nodes before linearized reading' (duration: 583.323627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:13.814614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:13.230841Z","time spent":"583.760515ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T11:28:13.814608Z","caller":"traceutil/trace.go:171","msg":"trace[72889433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28614; }","duration":"292.315636ms","start":"2026-08-22T11:28:13.522103Z","end":"2026-08-22T11:28:13.814419Z","steps":["trace[72889433] 'agreement among raft nodes before linearized reading' (duration: 291.870448ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.81436Z","caller":"traceutil/trace.go:171","msg":"trace[1602660136] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:28614; }","duration":"306.535018ms","start":"2026-08-22T11:28:13.507808Z","end":"2026-08-22T11:28:13.814343Z","steps":["trace[1602660136] 'agreement among raft nodes before linearized reading' (duration: 306.346985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:13.81474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:13.507793Z","time spent":"306.892426ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:28:13.82482Z","caller":"traceutil/trace.go:171","msg":"trace[1394947610] transaction","detail":"{read_only:false; response_revision:28615; number_of_response:1; }","duration":"283.834404ms","start":"2026-08-22T11:28:13.540948Z","end":"2026-08-22T11:28:13.824783Z","steps":["trace[1394947610] 'process raft request' (duration: 283.622701ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.825023Z","caller":"traceutil/trace.go:171","msg":"trace[1684764908] transaction","detail":"{read_only:false; response_revision:28616; number_of_response:1; }","duration":"269.659793ms","start":"2026-08-22T11:28:13.555343Z","end":"2026-08-22T11:28:13.825003Z","steps":["trace[1684764908] 'process raft request' (duration: 269.390469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:13.825131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.21474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11501"} {"level":"info","ts":"2026-08-22T11:28:13.825211Z","caller":"traceutil/trace.go:171","msg":"trace[1086171032] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28616; }","duration":"264.330122ms","start":"2026-08-22T11:28:13.560868Z","end":"2026-08-22T11:28:13.825198Z","steps":["trace[1086171032] 'agreement among raft nodes before linearized reading' (duration: 264.048637ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.952814Z","caller":"traceutil/trace.go:171","msg":"trace[1788938146] transaction","detail":"{read_only:false; response_revision:28617; number_of_response:1; }","duration":"133.623113ms","start":"2026-08-22T11:28:13.819164Z","end":"2026-08-22T11:28:13.952787Z","steps":["trace[1788938146] 'process raft request' (duration: 130.807539ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.953221Z","caller":"traceutil/trace.go:171","msg":"trace[1885316609] transaction","detail":"{read_only:false; response_revision:28618; number_of_response:1; }","duration":"132.941689ms","start":"2026-08-22T11:28:13.820265Z","end":"2026-08-22T11:28:13.953207Z","steps":["trace[1885316609] 'process raft request' (duration: 132.47301ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:13.953235Z","caller":"traceutil/trace.go:171","msg":"trace[1388098658] linearizableReadLoop","detail":"{readStateIndex:29935; appliedIndex:29933; }","duration":"126.504618ms","start":"2026-08-22T11:28:13.826688Z","end":"2026-08-22T11:28:13.953192Z","steps":["trace[1388098658] 'read index received' (duration: 123.254786ms)","trace[1388098658] 'applied index is now lower than readState.Index' (duration: 3.247862ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:13.953488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.772964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16994"} {"level":"info","ts":"2026-08-22T11:28:13.953559Z","caller":"traceutil/trace.go:171","msg":"trace[296853183] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:28618; }","duration":"126.875905ms","start":"2026-08-22T11:28:13.826669Z","end":"2026-08-22T11:28:13.953544Z","steps":["trace[296853183] 'agreement among raft nodes before linearized reading' (duration: 126.645371ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:14.115044Z","caller":"traceutil/trace.go:171","msg":"trace[1434352583] transaction","detail":"{read_only:false; response_revision:28621; number_of_response:1; }","duration":"128.877603ms","start":"2026-08-22T11:28:13.986141Z","end":"2026-08-22T11:28:14.115019Z","steps":["trace[1434352583] 'process raft request' (duration: 128.813042ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:14.115054Z","caller":"traceutil/trace.go:171","msg":"trace[1558756019] transaction","detail":"{read_only:false; response_revision:28619; number_of_response:1; }","duration":"286.589988ms","start":"2026-08-22T11:28:13.828412Z","end":"2026-08-22T11:28:14.115002Z","steps":["trace[1558756019] 'process raft request' (duration: 251.201682ms)","trace[1558756019] 'compare' (duration: 35.139791ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:14.115097Z","caller":"traceutil/trace.go:171","msg":"trace[1092112872] linearizableReadLoop","detail":"{readStateIndex:29937; appliedIndex:29935; }","duration":"149.468137ms","start":"2026-08-22T11:28:13.965589Z","end":"2026-08-22T11:28:14.115057Z","steps":["trace[1092112872] 'read index received' (duration: 114.04652ms)","trace[1092112872] 'applied index is now lower than readState.Index' (duration: 35.415687ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:14.115102Z","caller":"traceutil/trace.go:171","msg":"trace[348740162] transaction","detail":"{read_only:false; response_revision:28620; number_of_response:1; }","duration":"286.100198ms","start":"2026-08-22T11:28:13.828954Z","end":"2026-08-22T11:28:14.115054Z","steps":["trace[348740162] 'process raft request' (duration: 285.939935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:14.115408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.944275ms","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:8"} {"level":"warn","ts":"2026-08-22T11:28:14.115373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.748882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-08-22T11:28:14.115484Z","caller":"traceutil/trace.go:171","msg":"trace[1798732295] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:28621; }","duration":"105.042738ms","start":"2026-08-22T11:28:14.010399Z","end":"2026-08-22T11:28:14.115442Z","steps":["trace[1798732295] 'agreement among raft nodes before linearized reading' (duration: 104.854594ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:14.115533Z","caller":"traceutil/trace.go:171","msg":"trace[1613311329] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:28621; }","duration":"149.953776ms","start":"2026-08-22T11:28:13.965553Z","end":"2026-08-22T11:28:14.115507Z","steps":["trace[1613311329] 'agreement among raft nodes before linearized reading' (duration: 149.594499ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:14.317563Z","caller":"traceutil/trace.go:171","msg":"trace[1342472942] transaction","detail":"{read_only:false; response_revision:28623; number_of_response:1; }","duration":"162.184299ms","start":"2026-08-22T11:28:14.155346Z","end":"2026-08-22T11:28:14.317531Z","steps":["trace[1342472942] 'process raft request' (duration: 159.854625ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:14.77521Z","caller":"traceutil/trace.go:171","msg":"trace[1359846311] linearizableReadLoop","detail":"{readStateIndex:29942; appliedIndex:29941; }","duration":"175.830911ms","start":"2026-08-22T11:28:14.59936Z","end":"2026-08-22T11:28:14.775191Z","steps":["trace[1359846311] 'read index received' (duration: 175.650706ms)","trace[1359846311] 'applied index is now lower than readState.Index' (duration: 179.355µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:14.775328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.949243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:14.77535Z","caller":"traceutil/trace.go:171","msg":"trace[729446852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28625; }","duration":"176.005633ms","start":"2026-08-22T11:28:14.599338Z","end":"2026-08-22T11:28:14.775344Z","steps":["trace[729446852] 'agreement among raft nodes before linearized reading' (duration: 175.925492ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:14.775399Z","caller":"traceutil/trace.go:171","msg":"trace[1271476682] transaction","detail":"{read_only:false; response_revision:28625; number_of_response:1; }","duration":"198.112537ms","start":"2026-08-22T11:28:14.577254Z","end":"2026-08-22T11:28:14.775366Z","steps":["trace[1271476682] 'process raft request' (duration: 197.79684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:15.140765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.840813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:15.14094Z","caller":"traceutil/trace.go:171","msg":"trace[392110911] transaction","detail":"{read_only:false; response_revision:28627; number_of_response:1; }","duration":"232.176877ms","start":"2026-08-22T11:28:14.908743Z","end":"2026-08-22T11:28:15.14092Z","steps":["trace[392110911] 'process raft request' (duration: 115.086129ms)","trace[392110911] 'compare' (duration: 116.69553ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:15.351066Z","caller":"traceutil/trace.go:171","msg":"trace[1578976595] transaction","detail":"{read_only:false; response_revision:28628; number_of_response:1; }","duration":"123.589072ms","start":"2026-08-22T11:28:15.22742Z","end":"2026-08-22T11:28:15.351009Z","steps":["trace[1578976595] 'process raft request' (duration: 56.248375ms)","trace[1578976595] 'compare' (duration: 67.065922ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:15.771399Z","caller":"traceutil/trace.go:171","msg":"trace[1125432424] linearizableReadLoop","detail":"{readStateIndex:29947; appliedIndex:29946; }","duration":"235.630483ms","start":"2026-08-22T11:28:15.535746Z","end":"2026-08-22T11:28:15.771376Z","steps":["trace[1125432424] 'read index received' (duration: 235.498281ms)","trace[1125432424] 'applied index is now lower than readState.Index' (duration: 131.152µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:15.771562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.248932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:15.771534Z","caller":"traceutil/trace.go:171","msg":"trace[675789265] transaction","detail":"{read_only:false; response_revision:28630; number_of_response:1; }","duration":"237.499229ms","start":"2026-08-22T11:28:15.533974Z","end":"2026-08-22T11:28:15.771473Z","steps":["trace[675789265] 'process raft request' (duration: 237.277675ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:15.771587Z","caller":"traceutil/trace.go:171","msg":"trace[2130820690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28630; }","duration":"171.343354ms","start":"2026-08-22T11:28:15.600237Z","end":"2026-08-22T11:28:15.77158Z","steps":["trace[2130820690] 'agreement among raft nodes before linearized reading' (duration: 171.261252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:15.771645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.892958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.145\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-08-22T11:28:15.771672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.433999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:15.771775Z","caller":"traceutil/trace.go:171","msg":"trace[644015996] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:28630; }","duration":"167.577171ms","start":"2026-08-22T11:28:15.604181Z","end":"2026-08-22T11:28:15.771758Z","steps":["trace[644015996] 'agreement among raft nodes before linearized reading' (duration: 167.383998ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:15.771699Z","caller":"traceutil/trace.go:171","msg":"trace[335081561] range","detail":"{range_begin:/registry/masterleases/199.19.213.145; range_end:; response_count:1; response_revision:28630; }","duration":"235.99316ms","start":"2026-08-22T11:28:15.535693Z","end":"2026-08-22T11:28:15.771687Z","steps":["trace[335081561] 'agreement among raft nodes before linearized reading' (duration: 235.798946ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:15.926691Z","caller":"traceutil/trace.go:171","msg":"trace[1226024147] transaction","detail":"{read_only:false; response_revision:28631; number_of_response:1; }","duration":"138.966595ms","start":"2026-08-22T11:28:15.78758Z","end":"2026-08-22T11:28:15.926547Z","steps":["trace[1226024147] 'process raft request' (duration: 137.250752ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:15.926823Z","caller":"traceutil/trace.go:171","msg":"trace[1027656865] transaction","detail":"{read_only:false; response_revision:28632; number_of_response:1; }","duration":"107.967243ms","start":"2026-08-22T11:28:15.818822Z","end":"2026-08-22T11:28:15.92679Z","steps":["trace[1027656865] 'process raft request' (duration: 107.698488ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:15.926875Z","caller":"traceutil/trace.go:171","msg":"trace[899897314] transaction","detail":"{read_only:false; response_revision:28633; number_of_response:1; }","duration":"106.360783ms","start":"2026-08-22T11:28:15.820462Z","end":"2026-08-22T11:28:15.926823Z","steps":["trace[899897314] 'process raft request' (duration: 106.227611ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:16.093145Z","caller":"traceutil/trace.go:171","msg":"trace[1382128846] transaction","detail":"{read_only:false; response_revision:28636; number_of_response:1; }","duration":"131.270309ms","start":"2026-08-22T11:28:15.961814Z","end":"2026-08-22T11:28:16.093085Z","steps":["trace[1382128846] 'process raft request' (duration: 131.001794ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:16.229229Z","caller":"traceutil/trace.go:171","msg":"trace[504275747] linearizableReadLoop","detail":"{readStateIndex:29955; appliedIndex:29954; }","duration":"109.246338ms","start":"2026-08-22T11:28:16.119964Z","end":"2026-08-22T11:28:16.22921Z","steps":["trace[504275747] 'read index received' (duration: 105.491216ms)","trace[504275747] 'applied index is now lower than readState.Index' (duration: 3.753912ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:16.229356Z","caller":"traceutil/trace.go:171","msg":"trace[1611732541] transaction","detail":"{read_only:false; response_revision:28637; number_of_response:1; }","duration":"266.055665ms","start":"2026-08-22T11:28:15.963249Z","end":"2026-08-22T11:28:16.229305Z","steps":["trace[1611732541] 'process raft request' (duration: 262.12766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:16.229454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.467822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-08-22T11:28:16.229503Z","caller":"traceutil/trace.go:171","msg":"trace[809409358] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28637; }","duration":"109.574164ms","start":"2026-08-22T11:28:16.119919Z","end":"2026-08-22T11:28:16.229493Z","steps":["trace[809409358] 'agreement among raft nodes before linearized reading' (duration: 109.406201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:16.22958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.595164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:28:16.229654Z","caller":"traceutil/trace.go:171","msg":"trace[306824798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28637; }","duration":"109.707846ms","start":"2026-08-22T11:28:16.119925Z","end":"2026-08-22T11:28:16.229633Z","steps":["trace[306824798] 'agreement among raft nodes before linearized reading' (duration: 109.487912ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:16.407117Z","caller":"traceutil/trace.go:171","msg":"trace[1766782059] transaction","detail":"{read_only:false; response_revision:28638; number_of_response:1; }","duration":"173.088788ms","start":"2026-08-22T11:28:16.233987Z","end":"2026-08-22T11:28:16.407076Z","steps":["trace[1766782059] 'process raft request' (duration: 104.260993ms)","trace[1766782059] 'compare' (duration: 68.431768ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:16.407354Z","caller":"traceutil/trace.go:171","msg":"trace[899589665] transaction","detail":"{read_only:false; response_revision:28639; number_of_response:1; }","duration":"172.575008ms","start":"2026-08-22T11:28:16.234756Z","end":"2026-08-22T11:28:16.407331Z","steps":["trace[899589665] 'process raft request' (duration: 172.085279ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:16.573012Z","caller":"traceutil/trace.go:171","msg":"trace[1883567286] transaction","detail":"{read_only:false; response_revision:28642; number_of_response:1; }","duration":"147.04659ms","start":"2026-08-22T11:28:16.425924Z","end":"2026-08-22T11:28:16.57297Z","steps":["trace[1883567286] 'process raft request' (duration: 146.790244ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:17.021925Z","caller":"traceutil/trace.go:171","msg":"trace[1612684035] transaction","detail":"{read_only:false; response_revision:28643; number_of_response:1; }","duration":"152.362232ms","start":"2026-08-22T11:28:16.869524Z","end":"2026-08-22T11:28:17.021886Z","steps":["trace[1612684035] 'process raft request' (duration: 152.065376ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:17.28539Z","caller":"traceutil/trace.go:171","msg":"trace[1908622537] transaction","detail":"{read_only:false; response_revision:28645; number_of_response:1; }","duration":"139.442895ms","start":"2026-08-22T11:28:17.145923Z","end":"2026-08-22T11:28:17.285366Z","steps":["trace[1908622537] 'process raft request' (duration: 136.357616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:17.587869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.044775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:17.587966Z","caller":"traceutil/trace.go:171","msg":"trace[356455991] transaction","detail":"{read_only:false; response_revision:28646; number_of_response:1; }","duration":"226.459307ms","start":"2026-08-22T11:28:17.361485Z","end":"2026-08-22T11:28:17.587945Z","steps":["trace[356455991] 'process raft request' (duration: 119.272109ms)","trace[356455991] 'compare' (duration: 106.857852ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:21.952299Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"76a9cbc7029401f7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:28:21.958689Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-22T11:28:21.958821Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"warn","ts":"2026-08-22T11:28:26.303165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.091818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:26.303338Z","caller":"traceutil/trace.go:171","msg":"trace[379680179] transaction","detail":"{read_only:false; response_revision:28780; number_of_response:1; }","duration":"161.608099ms","start":"2026-08-22T11:28:26.14171Z","end":"2026-08-22T11:28:26.303318Z","steps":["trace[379680179] 'process raft request' (duration: 33.237835ms)","trace[379680179] 'compare' (duration: 127.866424ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:26.559927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.321391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:26.560122Z","caller":"traceutil/trace.go:171","msg":"trace[1358793534] transaction","detail":"{read_only:false; response_revision:28781; number_of_response:1; }","duration":"251.632029ms","start":"2026-08-22T11:28:26.308463Z","end":"2026-08-22T11:28:26.560095Z","steps":["trace[1358793534] 'process raft request' (duration: 145.011171ms)","trace[1358793534] 'compare' (duration: 106.050657ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:26.560186Z","caller":"traceutil/trace.go:171","msg":"trace[1355727131] linearizableReadLoop","detail":"{readStateIndex:30103; appliedIndex:30102; }","duration":"174.752499ms","start":"2026-08-22T11:28:26.385412Z","end":"2026-08-22T11:28:26.560164Z","steps":["trace[1355727131] 'read index received' (duration: 68.08038ms)","trace[1355727131] 'applied index is now lower than readState.Index' (duration: 106.668819ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:26.560247Z","caller":"traceutil/trace.go:171","msg":"trace[1133732123] transaction","detail":"{read_only:false; response_revision:28782; number_of_response:1; }","duration":"119.679707ms","start":"2026-08-22T11:28:26.440544Z","end":"2026-08-22T11:28:26.560224Z","steps":["trace[1133732123] 'process raft request' (duration: 119.536605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:26.560395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.880605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:26.560441Z","caller":"traceutil/trace.go:171","msg":"trace[253044379] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:28782; }","duration":"115.947636ms","start":"2026-08-22T11:28:26.444468Z","end":"2026-08-22T11:28:26.560415Z","steps":["trace[253044379] 'agreement among raft nodes before linearized reading' (duration: 115.859324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:26.560455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.7254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-08-22T11:28:26.560486Z","caller":"traceutil/trace.go:171","msg":"trace[186819453] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28782; }","duration":"105.817982ms","start":"2026-08-22T11:28:26.454658Z","end":"2026-08-22T11:28:26.560476Z","steps":["trace[186819453] 'agreement among raft nodes before linearized reading' (duration: 105.67909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:26.560477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.039635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca19f80b-861d-4208-b05f-7b73e6dec271\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-22T11:28:26.560573Z","caller":"traceutil/trace.go:171","msg":"trace[1020667664] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca19f80b-861d-4208-b05f-7b73e6dec271; range_end:; response_count:1; response_revision:28782; }","duration":"175.200669ms","start":"2026-08-22T11:28:26.38535Z","end":"2026-08-22T11:28:26.560551Z","steps":["trace[1020667664] 'agreement among raft nodes before linearized reading' (duration: 174.908523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:26.560649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.333088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:28:26.560774Z","caller":"traceutil/trace.go:171","msg":"trace[140909042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28782; }","duration":"110.541842ms","start":"2026-08-22T11:28:26.4502Z","end":"2026-08-22T11:28:26.560742Z","steps":["trace[140909042] 'agreement among raft nodes before linearized reading' (duration: 110.147545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:26.873635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.472835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:26.873804Z","caller":"traceutil/trace.go:171","msg":"trace[1358560267] transaction","detail":"{read_only:false; response_revision:28783; number_of_response:1; }","duration":"309.473314ms","start":"2026-08-22T11:28:26.564301Z","end":"2026-08-22T11:28:26.873774Z","steps":["trace[1358560267] 'process raft request' (duration: 136.715612ms)","trace[1358560267] 'compare' (duration: 172.16524ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:26.87388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:26.564273Z","time spent":"309.573126ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:28:26.88932Z","caller":"traceutil/trace.go:171","msg":"trace[1591099454] linearizableReadLoop","detail":"{readStateIndex:30107; appliedIndex:30104; }","duration":"287.557686ms","start":"2026-08-22T11:28:26.601732Z","end":"2026-08-22T11:28:26.889289Z","steps":["trace[1591099454] 'read index received' (duration: 99.319668ms)","trace[1591099454] 'applied index is now lower than readState.Index' (duration: 188.236618ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:26.889505Z","caller":"traceutil/trace.go:171","msg":"trace[1933420895] transaction","detail":"{read_only:false; response_revision:28785; number_of_response:1; }","duration":"321.959363ms","start":"2026-08-22T11:28:26.56744Z","end":"2026-08-22T11:28:26.8894Z","steps":["trace[1933420895] 'process raft request' (duration: 321.77775ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:26.889522Z","caller":"traceutil/trace.go:171","msg":"trace[1881557508] transaction","detail":"{read_only:false; response_revision:28784; number_of_response:1; }","duration":"324.602424ms","start":"2026-08-22T11:28:26.564886Z","end":"2026-08-22T11:28:26.889489Z","steps":["trace[1881557508] 'process raft request' (duration: 324.168185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:26.889549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.79454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:26.889633Z","caller":"traceutil/trace.go:171","msg":"trace[442942847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28785; }","duration":"287.932773ms","start":"2026-08-22T11:28:26.601677Z","end":"2026-08-22T11:28:26.88961Z","steps":["trace[442942847] 'agreement among raft nodes before linearized reading' (duration: 287.76319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:26.889604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.966452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-08-22T11:28:26.889689Z","caller":"traceutil/trace.go:171","msg":"trace[1561217565] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28785; }","duration":"233.114585ms","start":"2026-08-22T11:28:26.65656Z","end":"2026-08-22T11:28:26.889675Z","steps":["trace[1561217565] 'agreement among raft nodes before linearized reading' (duration: 232.885411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:26.889696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:26.564863Z","time spent":"324.740876ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:28:26.889727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:26.567415Z","time spent":"322.177907ms","remote":"127.0.0.1:55484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:28:27.160014Z","caller":"traceutil/trace.go:171","msg":"trace[1821136713] linearizableReadLoop","detail":"{readStateIndex:30108; appliedIndex:30107; }","duration":"179.960289ms","start":"2026-08-22T11:28:26.980026Z","end":"2026-08-22T11:28:27.159986Z","steps":["trace[1821136713] 'read index received' (duration: 179.794426ms)","trace[1821136713] 'applied index is now lower than readState.Index' (duration: 164.763µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:27.160132Z","caller":"traceutil/trace.go:171","msg":"trace[1795699484] transaction","detail":"{read_only:false; response_revision:28786; number_of_response:1; }","duration":"262.721251ms","start":"2026-08-22T11:28:26.897358Z","end":"2026-08-22T11:28:27.16008Z","steps":["trace[1795699484] 'process raft request' (duration: 262.440246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:27.160223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.174924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:28:27.160436Z","caller":"traceutil/trace.go:171","msg":"trace[1208889280] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:28786; }","duration":"180.453668ms","start":"2026-08-22T11:28:26.979962Z","end":"2026-08-22T11:28:27.160416Z","steps":["trace[1208889280] 'agreement among raft nodes before linearized reading' (duration: 180.126782ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:27.331242Z","caller":"traceutil/trace.go:171","msg":"trace[1910600286] linearizableReadLoop","detail":"{readStateIndex:30109; appliedIndex:30108; }","duration":"119.392711ms","start":"2026-08-22T11:28:27.211824Z","end":"2026-08-22T11:28:27.331216Z","steps":["trace[1910600286] 'read index received' (duration: 49.371673ms)","trace[1910600286] 'applied index is now lower than readState.Index' (duration: 70.019588ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:27.331283Z","caller":"traceutil/trace.go:171","msg":"trace[495594594] transaction","detail":"{read_only:false; response_revision:28787; number_of_response:1; }","duration":"161.512657ms","start":"2026-08-22T11:28:27.16972Z","end":"2026-08-22T11:28:27.331233Z","steps":["trace[495594594] 'process raft request' (duration: 91.57622ms)","trace[495594594] 'compare' (duration: 69.796124ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:27.331721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.854111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-08-22T11:28:27.331812Z","caller":"traceutil/trace.go:171","msg":"trace[1792217302] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28787; }","duration":"120.039435ms","start":"2026-08-22T11:28:27.211751Z","end":"2026-08-22T11:28:27.33179Z","steps":["trace[1792217302] 'agreement among raft nodes before linearized reading' (duration: 119.575785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:27.570828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.439057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:27.570909Z","caller":"traceutil/trace.go:171","msg":"trace[317440918] linearizableReadLoop","detail":"{readStateIndex:30110; appliedIndex:30109; }","duration":"239.573519ms","start":"2026-08-22T11:28:27.331321Z","end":"2026-08-22T11:28:27.570894Z","steps":["trace[317440918] 'read index received' (duration: 101.958249ms)","trace[317440918] 'applied index is now lower than readState.Index' (duration: 137.61389ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:27.571064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.609165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:27.571149Z","caller":"traceutil/trace.go:171","msg":"trace[1038297833] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:28788; }","duration":"256.746587ms","start":"2026-08-22T11:28:27.314383Z","end":"2026-08-22T11:28:27.571129Z","steps":["trace[1038297833] 'agreement among raft nodes before linearized reading' (duration: 256.598394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:27.571277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.555309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca19f80b-861d-4208-b05f-7b73e6dec271\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-08-22T11:28:27.571305Z","caller":"traceutil/trace.go:171","msg":"trace[1095274450] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca19f80b-861d-4208-b05f-7b73e6dec271; range_end:; response_count:1; response_revision:28788; }","duration":"261.64077ms","start":"2026-08-22T11:28:27.309656Z","end":"2026-08-22T11:28:27.571297Z","steps":["trace[1095274450] 'agreement among raft nodes before linearized reading' (duration: 261.504578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:27.571636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.202397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-08-22T11:28:27.5717Z","caller":"traceutil/trace.go:171","msg":"trace[1045189242] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:28788; }","duration":"323.328479ms","start":"2026-08-22T11:28:27.248356Z","end":"2026-08-22T11:28:27.571685Z","steps":["trace[1045189242] 'agreement among raft nodes before linearized reading' (duration: 323.135696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:27.571745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:27.248278Z","time spent":"323.456252ms","remote":"127.0.0.1:55456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1873,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-08-22T11:28:27.571734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.907487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-22T11:28:27.571234Z","caller":"traceutil/trace.go:171","msg":"trace[1673951550] transaction","detail":"{read_only:false; response_revision:28788; number_of_response:1; }","duration":"243.921852ms","start":"2026-08-22T11:28:27.327277Z","end":"2026-08-22T11:28:27.571199Z","steps":["trace[1673951550] 'process raft request' (duration: 106.024856ms)","trace[1673951550] 'compare' (duration: 137.351205ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:27.571891Z","caller":"traceutil/trace.go:171","msg":"trace[263149595] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28788; }","duration":"237.0866ms","start":"2026-08-22T11:28:27.334761Z","end":"2026-08-22T11:28:27.571847Z","steps":["trace[263149595] 'agreement among raft nodes before linearized reading' (duration: 236.725004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:27.571616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.681088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:27.572074Z","caller":"traceutil/trace.go:171","msg":"trace[1018374344] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:28788; }","duration":"107.169088ms","start":"2026-08-22T11:28:27.464892Z","end":"2026-08-22T11:28:27.572061Z","steps":["trace[1018374344] 'agreement among raft nodes before linearized reading' (duration: 106.606088ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:27.755575Z","caller":"traceutil/trace.go:171","msg":"trace[2120437731] linearizableReadLoop","detail":"{readStateIndex:30112; appliedIndex:30111; }","duration":"127.540848ms","start":"2026-08-22T11:28:27.627993Z","end":"2026-08-22T11:28:27.755534Z","steps":["trace[2120437731] 'read index received' (duration: 127.256092ms)","trace[2120437731] 'applied index is now lower than readState.Index' (duration: 283.075µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:27.755658Z","caller":"traceutil/trace.go:171","msg":"trace[1364785875] transaction","detail":"{read_only:false; response_revision:28790; number_of_response:1; }","duration":"150.73368ms","start":"2026-08-22T11:28:27.604882Z","end":"2026-08-22T11:28:27.755616Z","steps":["trace[1364785875] 'process raft request' (duration: 150.405024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:27.755866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.849484ms","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-08-22T11:28:27.755914Z","caller":"traceutil/trace.go:171","msg":"trace[1730767550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28790; }","duration":"127.975056ms","start":"2026-08-22T11:28:27.627925Z","end":"2026-08-22T11:28:27.7559Z","steps":["trace[1730767550] 'agreement among raft nodes before linearized reading' (duration: 127.751511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:29.766534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.095186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11501"} {"level":"info","ts":"2026-08-22T11:28:29.766615Z","caller":"traceutil/trace.go:171","msg":"trace[1095590209] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28807; }","duration":"231.227118ms","start":"2026-08-22T11:28:29.535368Z","end":"2026-08-22T11:28:29.766596Z","steps":["trace[1095590209] 'range keys from in-memory index tree' (duration: 230.860301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:29.766717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.099426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:28:29.766791Z","caller":"traceutil/trace.go:171","msg":"trace[1815566192] linearizableReadLoop","detail":"{readStateIndex:30131; appliedIndex:30130; }","duration":"185.135867ms","start":"2026-08-22T11:28:29.581642Z","end":"2026-08-22T11:28:29.766778Z","steps":["trace[1815566192] 'read index received' (duration: 53.95794ms)","trace[1815566192] 'applied index is now lower than readState.Index' (duration: 131.176947ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:29.766972Z","caller":"traceutil/trace.go:171","msg":"trace[1816183397] transaction","detail":"{read_only:false; response_revision:28808; number_of_response:1; }","duration":"188.121064ms","start":"2026-08-22T11:28:29.578821Z","end":"2026-08-22T11:28:29.766942Z","steps":["trace[1816183397] 'process raft request' (duration: 56.724243ms)","trace[1816183397] 'compare' (duration: 130.957173ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:28:29.766996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.360682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca19f80b-861d-4208-b05f-7b73e6dec271\" ","response":"range_response_count:1 size:3947"} {"level":"warn","ts":"2026-08-22T11:28:29.767034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.461789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:28:29.76704Z","caller":"traceutil/trace.go:171","msg":"trace[509213502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca19f80b-861d-4208-b05f-7b73e6dec271; range_end:; response_count:1; response_revision:28808; }","duration":"185.453104ms","start":"2026-08-22T11:28:29.581571Z","end":"2026-08-22T11:28:29.767024Z","steps":["trace[509213502] 'agreement among raft nodes before linearized reading' (duration: 185.339782ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:29.767057Z","caller":"traceutil/trace.go:171","msg":"trace[1472146987] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:28808; }","duration":"157.49965ms","start":"2026-08-22T11:28:29.60955Z","end":"2026-08-22T11:28:29.76705Z","steps":["trace[1472146987] 'agreement among raft nodes before linearized reading' (duration: 157.412409ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:29.905086Z","caller":"traceutil/trace.go:171","msg":"trace[1434861793] transaction","detail":"{read_only:false; response_revision:28810; number_of_response:1; }","duration":"100.224706ms","start":"2026-08-22T11:28:29.804838Z","end":"2026-08-22T11:28:29.905063Z","steps":["trace[1434861793] 'process raft request' (duration: 100.098162ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:32.452059Z","caller":"traceutil/trace.go:171","msg":"trace[1580616213] transaction","detail":"{read_only:false; response_revision:28849; number_of_response:1; }","duration":"119.187618ms","start":"2026-08-22T11:28:32.332822Z","end":"2026-08-22T11:28:32.452009Z","steps":["trace[1580616213] 'process raft request' (duration: 118.879111ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:32.759095Z","caller":"traceutil/trace.go:171","msg":"trace[120089218] transaction","detail":"{read_only:false; response_revision:28850; number_of_response:1; }","duration":"231.422193ms","start":"2026-08-22T11:28:32.52764Z","end":"2026-08-22T11:28:32.759062Z","steps":["trace[120089218] 'process raft request' (duration: 231.200749ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:32.796203Z","caller":"traceutil/trace.go:171","msg":"trace[1451246372] linearizableReadLoop","detail":"{readStateIndex:30176; appliedIndex:30174; }","duration":"212.430918ms","start":"2026-08-22T11:28:32.583744Z","end":"2026-08-22T11:28:32.796175Z","steps":["trace[1451246372] 'read index received' (duration: 175.053275ms)","trace[1451246372] 'applied index is now lower than readState.Index' (duration: 37.376343ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:32.796288Z","caller":"traceutil/trace.go:171","msg":"trace[155907182] transaction","detail":"{read_only:false; response_revision:28851; number_of_response:1; }","duration":"216.156391ms","start":"2026-08-22T11:28:32.580112Z","end":"2026-08-22T11:28:32.796268Z","steps":["trace[155907182] 'process raft request' (duration: 215.911616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:32.796435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.662424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T11:28:32.796467Z","caller":"traceutil/trace.go:171","msg":"trace[862859366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28851; }","duration":"212.745076ms","start":"2026-08-22T11:28:32.583713Z","end":"2026-08-22T11:28:32.796458Z","steps":["trace[862859366] 'agreement among raft nodes before linearized reading' (duration: 212.583343ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:32.890188Z","caller":"traceutil/trace.go:171","msg":"trace[266949959] transaction","detail":"{read_only:false; response_revision:28852; number_of_response:1; }","duration":"122.920139ms","start":"2026-08-22T11:28:32.767223Z","end":"2026-08-22T11:28:32.890143Z","steps":["trace[266949959] 'process raft request' (duration: 99.253887ms)","trace[266949959] 'compare' (duration: 23.342375ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:32.890227Z","caller":"traceutil/trace.go:171","msg":"trace[1928145184] transaction","detail":"{read_only:false; response_revision:28853; number_of_response:1; }","duration":"122.025243ms","start":"2026-08-22T11:28:32.76817Z","end":"2026-08-22T11:28:32.890195Z","steps":["trace[1928145184] 'process raft request' (duration: 121.832739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:32.890659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.483833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:32.890693Z","caller":"traceutil/trace.go:171","msg":"trace[1438680700] transaction","detail":"{read_only:false; response_revision:28854; number_of_response:1; }","duration":"121.443301ms","start":"2026-08-22T11:28:32.769224Z","end":"2026-08-22T11:28:32.890667Z","steps":["trace[1438680700] 'process raft request' (duration: 120.86887ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:32.890738Z","caller":"traceutil/trace.go:171","msg":"trace[1504169908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28856; }","duration":"289.602745ms","start":"2026-08-22T11:28:32.601109Z","end":"2026-08-22T11:28:32.890712Z","steps":["trace[1504169908] 'agreement among raft nodes before linearized reading' (duration: 289.439892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:32.890793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.026473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:28:32.890842Z","caller":"traceutil/trace.go:171","msg":"trace[540854325] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28856; }","duration":"143.148816ms","start":"2026-08-22T11:28:32.747679Z","end":"2026-08-22T11:28:32.890827Z","steps":["trace[540854325] 'agreement among raft nodes before linearized reading' (duration: 143.023764ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:32.890871Z","caller":"traceutil/trace.go:171","msg":"trace[313100345] transaction","detail":"{read_only:false; response_revision:28855; number_of_response:1; }","duration":"121.322689ms","start":"2026-08-22T11:28:32.76953Z","end":"2026-08-22T11:28:32.890852Z","steps":["trace[313100345] 'process raft request' (duration: 120.8358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:32.891015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.57158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-22T11:28:32.891103Z","caller":"traceutil/trace.go:171","msg":"trace[1484850880] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:28856; }","duration":"271.657902ms","start":"2026-08-22T11:28:32.619423Z","end":"2026-08-22T11:28:32.891081Z","steps":["trace[1484850880] 'agreement among raft nodes before linearized reading' (duration: 271.415897ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.15577Z","caller":"traceutil/trace.go:171","msg":"trace[810206959] transaction","detail":"{read_only:false; response_revision:29146; number_of_response:1; }","duration":"232.734387ms","start":"2026-08-22T11:29:03.923015Z","end":"2026-08-22T11:29:04.155749Z","steps":["trace[810206959] 'process raft request' (duration: 232.407651ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.278453Z","caller":"traceutil/trace.go:171","msg":"trace[348821381] transaction","detail":"{read_only:false; response_revision:29147; number_of_response:1; }","duration":"188.265928ms","start":"2026-08-22T11:29:04.090164Z","end":"2026-08-22T11:29:04.27843Z","steps":["trace[348821381] 'process raft request' (duration: 187.260179ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.278819Z","caller":"traceutil/trace.go:171","msg":"trace[2033857054] linearizableReadLoop","detail":"{readStateIndex:30486; appliedIndex:30483; }","duration":"120.781388ms","start":"2026-08-22T11:29:04.158011Z","end":"2026-08-22T11:29:04.278792Z","steps":["trace[2033857054] 'read index received' (duration: 119.412702ms)","trace[2033857054] 'applied index is now lower than readState.Index' (duration: 1.367656ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:04.279034Z","caller":"traceutil/trace.go:171","msg":"trace[1740158314] transaction","detail":"{read_only:false; response_revision:29149; number_of_response:1; }","duration":"177.442602ms","start":"2026-08-22T11:29:04.101577Z","end":"2026-08-22T11:29:04.27902Z","steps":["trace[1740158314] 'process raft request' (duration: 177.084865ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.278902Z","caller":"traceutil/trace.go:171","msg":"trace[1839474018] transaction","detail":"{read_only:false; response_revision:29148; number_of_response:1; }","duration":"186.186468ms","start":"2026-08-22T11:29:04.092674Z","end":"2026-08-22T11:29:04.278861Z","steps":["trace[1839474018] 'process raft request' (duration: 185.703279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.279565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.41771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T11:29:04.279801Z","caller":"traceutil/trace.go:171","msg":"trace[1243315668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29149; }","duration":"121.783568ms","start":"2026-08-22T11:29:04.157983Z","end":"2026-08-22T11:29:04.279766Z","steps":["trace[1243315668] 'agreement among raft nodes before linearized reading' (duration: 121.057093ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.288966Z","caller":"traceutil/trace.go:171","msg":"trace[124172128] transaction","detail":"{read_only:false; response_revision:29150; number_of_response:1; }","duration":"127.482386ms","start":"2026-08-22T11:29:04.16146Z","end":"2026-08-22T11:29:04.288942Z","steps":["trace[124172128] 'process raft request' (duration: 127.343634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.289011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.49587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:04.289096Z","caller":"traceutil/trace.go:171","msg":"trace[117124482] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:29150; }","duration":"123.644473ms","start":"2026-08-22T11:29:04.165431Z","end":"2026-08-22T11:29:04.289075Z","steps":["trace[117124482] 'agreement among raft nodes before linearized reading' (duration: 123.47348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.510306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.235325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:04.510451Z","caller":"traceutil/trace.go:171","msg":"trace[1045431868] linearizableReadLoop","detail":"{readStateIndex:30488; appliedIndex:30487; }","duration":"218.264331ms","start":"2026-08-22T11:29:04.292168Z","end":"2026-08-22T11:29:04.510432Z","steps":["trace[1045431868] 'read index received' (duration: 105.744351ms)","trace[1045431868] 'applied index is now lower than readState.Index' (duration: 112.49798ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:04.510471Z","caller":"traceutil/trace.go:171","msg":"trace[363295958] transaction","detail":"{read_only:false; response_revision:29151; number_of_response:1; }","duration":"225.362047ms","start":"2026-08-22T11:29:04.285071Z","end":"2026-08-22T11:29:04.510433Z","steps":["trace[363295958] 'process raft request' (duration: 112.902088ms)","trace[363295958] 'compare' (duration: 112.055572ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:04.510652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.499466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-22T11:29:04.510704Z","caller":"traceutil/trace.go:171","msg":"trace[511171728] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29151; }","duration":"218.612279ms","start":"2026-08-22T11:29:04.292077Z","end":"2026-08-22T11:29:04.510689Z","steps":["trace[511171728] 'agreement among raft nodes before linearized reading' (duration: 218.410664ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.787745Z","caller":"traceutil/trace.go:171","msg":"trace[115905873] linearizableReadLoop","detail":"{readStateIndex:30489; appliedIndex:30488; }","duration":"164.702757ms","start":"2026-08-22T11:29:04.623015Z","end":"2026-08-22T11:29:04.787718Z","steps":["trace[115905873] 'read index received' (duration: 164.467533ms)","trace[115905873] 'applied index is now lower than readState.Index' (duration: 234.304µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:04.787844Z","caller":"traceutil/trace.go:171","msg":"trace[1631038141] transaction","detail":"{read_only:false; response_revision:29152; number_of_response:1; }","duration":"273.359684ms","start":"2026-08-22T11:29:04.514457Z","end":"2026-08-22T11:29:04.787817Z","steps":["trace[1631038141] 'process raft request' (duration: 273.104079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.787986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.509046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:04.78805Z","caller":"traceutil/trace.go:171","msg":"trace[1572061243] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:29152; }","duration":"151.648859ms","start":"2026-08-22T11:29:04.636384Z","end":"2026-08-22T11:29:04.788033Z","steps":["trace[1572061243] 'agreement among raft nodes before linearized reading' (duration: 151.527347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.788098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.046644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T11:29:04.78821Z","caller":"traceutil/trace.go:171","msg":"trace[1237335842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29152; }","duration":"165.227958ms","start":"2026-08-22T11:29:04.622952Z","end":"2026-08-22T11:29:04.78818Z","steps":["trace[1237335842] 'agreement among raft nodes before linearized reading' (duration: 164.87725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.076874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.11064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:05.077097Z","caller":"traceutil/trace.go:171","msg":"trace[496391773] linearizableReadLoop","detail":"{readStateIndex:30492; appliedIndex:30490; }","duration":"181.102341ms","start":"2026-08-22T11:29:04.895978Z","end":"2026-08-22T11:29:05.07708Z","steps":["trace[496391773] 'read index received' (duration: 31.731286ms)","trace[496391773] 'applied index is now lower than readState.Index' (duration: 149.370275ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:05.077155Z","caller":"traceutil/trace.go:171","msg":"trace[1956217470] transaction","detail":"{read_only:false; response_revision:29155; number_of_response:1; }","duration":"276.33612ms","start":"2026-08-22T11:29:04.800796Z","end":"2026-08-22T11:29:05.077133Z","steps":["trace[1956217470] 'process raft request' (duration: 276.182028ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:05.077212Z","caller":"traceutil/trace.go:171","msg":"trace[1476614438] transaction","detail":"{read_only:false; response_revision:29154; number_of_response:1; }","duration":"281.563131ms","start":"2026-08-22T11:29:04.795613Z","end":"2026-08-22T11:29:05.077176Z","steps":["trace[1476614438] 'process raft request' (duration: 132.058014ms)","trace[1476614438] 'compare' (duration: 148.675462ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:05.077441Z","caller":"traceutil/trace.go:171","msg":"trace[753114722] transaction","detail":"{read_only:false; response_revision:29156; number_of_response:1; }","duration":"169.885877ms","start":"2026-08-22T11:29:04.907532Z","end":"2026-08-22T11:29:05.077418Z","steps":["trace[753114722] 'process raft request' (duration: 169.51697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.0775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.501648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-08-22T11:29:05.077566Z","caller":"traceutil/trace.go:171","msg":"trace[1736291711] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:29156; }","duration":"181.62566ms","start":"2026-08-22T11:29:04.895922Z","end":"2026-08-22T11:29:05.077547Z","steps":["trace[1736291711] 'agreement among raft nodes before linearized reading' (duration: 181.238503ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:05.231937Z","caller":"traceutil/trace.go:171","msg":"trace[671452095] transaction","detail":"{read_only:false; response_revision:29157; number_of_response:1; }","duration":"150.09903ms","start":"2026-08-22T11:29:05.081809Z","end":"2026-08-22T11:29:05.231908Z","steps":["trace[671452095] 'process raft request' (duration: 149.907785ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:05.360911Z","caller":"traceutil/trace.go:171","msg":"trace[1289940328] linearizableReadLoop","detail":"{readStateIndex:30496; appliedIndex:30493; }","duration":"217.953966ms","start":"2026-08-22T11:29:05.142934Z","end":"2026-08-22T11:29:05.360888Z","steps":["trace[1289940328] 'read index received' (duration: 88.775426ms)","trace[1289940328] 'applied index is now lower than readState.Index' (duration: 129.1771ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:05.360968Z","caller":"traceutil/trace.go:171","msg":"trace[10284361] transaction","detail":"{read_only:false; response_revision:29158; number_of_response:1; }","duration":"278.246858ms","start":"2026-08-22T11:29:05.082677Z","end":"2026-08-22T11:29:05.360924Z","steps":["trace[10284361] 'process raft request' (duration: 277.998124ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:05.361042Z","caller":"traceutil/trace.go:171","msg":"trace[573251848] transaction","detail":"{read_only:false; response_revision:29159; number_of_response:1; }","duration":"278.046824ms","start":"2026-08-22T11:29:05.082964Z","end":"2026-08-22T11:29:05.361011Z","steps":["trace[573251848] 'process raft request' (duration: 277.873831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.361168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.21494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:05.361262Z","caller":"traceutil/trace.go:171","msg":"trace[1404967452] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:29159; }","duration":"218.368154ms","start":"2026-08-22T11:29:05.142855Z","end":"2026-08-22T11:29:05.361224Z","steps":["trace[1404967452] 'agreement among raft nodes before linearized reading' (duration: 218.232751ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:05.55155Z","caller":"traceutil/trace.go:171","msg":"trace[1523830889] transaction","detail":"{read_only:false; response_revision:29160; number_of_response:1; }","duration":"184.460725ms","start":"2026-08-22T11:29:05.367045Z","end":"2026-08-22T11:29:05.551505Z","steps":["trace[1523830889] 'process raft request' (duration: 149.400375ms)","trace[1523830889] 'compare' (duration: 34.786055ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:05.857329Z","caller":"traceutil/trace.go:171","msg":"trace[751664994] linearizableReadLoop","detail":"{readStateIndex:30500; appliedIndex:30499; }","duration":"162.816521ms","start":"2026-08-22T11:29:05.694492Z","end":"2026-08-22T11:29:05.857309Z","steps":["trace[751664994] 'read index received' (duration: 162.588587ms)","trace[751664994] 'applied index is now lower than readState.Index' (duration: 227.004µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:05.85741Z","caller":"traceutil/trace.go:171","msg":"trace[423484960] transaction","detail":"{read_only:false; response_revision:29162; number_of_response:1; }","duration":"222.048623ms","start":"2026-08-22T11:29:05.635322Z","end":"2026-08-22T11:29:05.857371Z","steps":["trace[423484960] 'process raft request' (duration: 221.836809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.857635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.099747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:05.857673Z","caller":"traceutil/trace.go:171","msg":"trace[2041646293] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:29162; }","duration":"163.227179ms","start":"2026-08-22T11:29:05.694431Z","end":"2026-08-22T11:29:05.857658Z","steps":["trace[2041646293] 'agreement among raft nodes before linearized reading' (duration: 162.978524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.857969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.936526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-08-22T11:29:05.858018Z","caller":"traceutil/trace.go:171","msg":"trace[1138100991] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29162; }","duration":"127.067669ms","start":"2026-08-22T11:29:05.730933Z","end":"2026-08-22T11:29:05.858001Z","steps":["trace[1138100991] 'agreement among raft nodes before linearized reading' (duration: 126.874785ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.112796Z","caller":"traceutil/trace.go:171","msg":"trace[2015386006] transaction","detail":"{read_only:false; number_of_response:0; response_revision:29162; }","duration":"246.869398ms","start":"2026-08-22T11:29:05.865891Z","end":"2026-08-22T11:29:06.112761Z","steps":["trace[2015386006] 'process raft request' (duration: 246.684155ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.124419Z","caller":"traceutil/trace.go:171","msg":"trace[1182204879] transaction","detail":"{read_only:false; response_revision:29163; number_of_response:1; }","duration":"257.879768ms","start":"2026-08-22T11:29:05.866508Z","end":"2026-08-22T11:29:06.124388Z","steps":["trace[1182204879] 'process raft request' (duration: 257.544672ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.264045Z","caller":"traceutil/trace.go:171","msg":"trace[1735620800] transaction","detail":"{read_only:false; response_revision:29164; number_of_response:1; }","duration":"130.528014ms","start":"2026-08-22T11:29:06.133491Z","end":"2026-08-22T11:29:06.264019Z","steps":["trace[1735620800] 'process raft request' (duration: 130.347451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:06.550759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.278029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:06.551035Z","caller":"traceutil/trace.go:171","msg":"trace[1491061733] linearizableReadLoop","detail":"{readStateIndex:30505; appliedIndex:30502; }","duration":"318.998936ms","start":"2026-08-22T11:29:06.232021Z","end":"2026-08-22T11:29:06.55102Z","steps":["trace[1491061733] 'read index received' (duration: 31.831088ms)","trace[1491061733] 'applied index is now lower than readState.Index' (duration: 287.166688ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:06.551142Z","caller":"traceutil/trace.go:171","msg":"trace[737593691] transaction","detail":"{read_only:false; response_revision:29166; number_of_response:1; }","duration":"389.430433ms","start":"2026-08-22T11:29:06.161667Z","end":"2026-08-22T11:29:06.551097Z","steps":["trace[737593691] 'process raft request' (duration: 389.201578ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.551164Z","caller":"traceutil/trace.go:171","msg":"trace[640950394] transaction","detail":"{read_only:false; response_revision:29165; number_of_response:1; }","duration":"414.797457ms","start":"2026-08-22T11:29:06.136332Z","end":"2026-08-22T11:29:06.551129Z","steps":["trace[640950394] 'process raft request' (duration: 262.044648ms)","trace[640950394] 'compare' (duration: 152.001154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:06.551305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.290302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"warn","ts":"2026-08-22T11:29:06.551321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:06.161653Z","time spent":"389.567095ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:06.551352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:06.136292Z","time spent":"414.96255ms","remote":"127.0.0.1:55802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:06.55134Z","caller":"traceutil/trace.go:171","msg":"trace[357562678] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:29166; }","duration":"319.361183ms","start":"2026-08-22T11:29:06.231965Z","end":"2026-08-22T11:29:06.551326Z","steps":["trace[357562678] 'agreement among raft nodes before linearized reading' (duration: 319.119988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:06.551472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:06.231942Z","time spent":"319.500206ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-22T11:29:06.551776Z","caller":"traceutil/trace.go:171","msg":"trace[1986013141] transaction","detail":"{read_only:false; response_revision:29167; number_of_response:1; }","duration":"267.096514ms","start":"2026-08-22T11:29:06.284662Z","end":"2026-08-22T11:29:06.551759Z","steps":["trace[1986013141] 'process raft request' (duration: 266.756157ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.552042Z","caller":"traceutil/trace.go:171","msg":"trace[1932124297] transaction","detail":"{read_only:false; response_revision:29168; number_of_response:1; }","duration":"267.304608ms","start":"2026-08-22T11:29:06.284717Z","end":"2026-08-22T11:29:06.552022Z","steps":["trace[1932124297] 'process raft request' (duration: 266.968201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:06.552601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.250344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T11:29:06.552653Z","caller":"traceutil/trace.go:171","msg":"trace[592844724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29169; }","duration":"260.324875ms","start":"2026-08-22T11:29:06.292314Z","end":"2026-08-22T11:29:06.552639Z","steps":["trace[592844724] 'agreement among raft nodes before linearized reading' (duration: 260.110731ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.552761Z","caller":"traceutil/trace.go:171","msg":"trace[1803097351] transaction","detail":"{read_only:false; response_revision:29169; number_of_response:1; }","duration":"267.94175ms","start":"2026-08-22T11:29:06.28481Z","end":"2026-08-22T11:29:06.552751Z","steps":["trace[1803097351] 'process raft request' (duration: 267.258496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:06.552832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.87632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:29:06.552889Z","caller":"traceutil/trace.go:171","msg":"trace[2143539778] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:29169; }","duration":"131.942651ms","start":"2026-08-22T11:29:06.420932Z","end":"2026-08-22T11:29:06.552875Z","steps":["trace[2143539778] 'agreement among raft nodes before linearized reading' (duration: 131.825559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:06.552905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.717341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-22T11:29:06.552937Z","caller":"traceutil/trace.go:171","msg":"trace[1127214186] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:29169; }","duration":"239.794932ms","start":"2026-08-22T11:29:06.313131Z","end":"2026-08-22T11:29:06.552926Z","steps":["trace[1127214186] 'agreement among raft nodes before linearized reading' (duration: 239.70095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:06.854905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.467877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:06.855035Z","caller":"traceutil/trace.go:171","msg":"trace[1660452496] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:29172; }","duration":"149.715992ms","start":"2026-08-22T11:29:06.705299Z","end":"2026-08-22T11:29:06.855015Z","steps":["trace[1660452496] 'count revisions from in-memory index tree' (duration: 149.359985ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:07.352393Z","caller":"traceutil/trace.go:171","msg":"trace[1364626313] linearizableReadLoop","detail":"{readStateIndex:30514; appliedIndex:30513; }","duration":"267.499882ms","start":"2026-08-22T11:29:07.084866Z","end":"2026-08-22T11:29:07.352366Z","steps":["trace[1364626313] 'read index received' (duration: 267.321449ms)","trace[1364626313] 'applied index is now lower than readState.Index' (duration: 177.023µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:07.352445Z","caller":"traceutil/trace.go:171","msg":"trace[1038227911] transaction","detail":"{read_only:false; response_revision:29175; number_of_response:1; }","duration":"268.499861ms","start":"2026-08-22T11:29:07.083918Z","end":"2026-08-22T11:29:07.352418Z","steps":["trace[1038227911] 'process raft request' (duration: 268.264197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:07.352603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.725336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-22T11:29:07.352805Z","caller":"traceutil/trace.go:171","msg":"trace[151266436] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29175; }","duration":"267.958711ms","start":"2026-08-22T11:29:07.084828Z","end":"2026-08-22T11:29:07.352787Z","steps":["trace[151266436] 'agreement among raft nodes before linearized reading' (duration: 267.632535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:07.353375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.704161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-22T11:29:07.353512Z","caller":"traceutil/trace.go:171","msg":"trace[1499167855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29175; }","duration":"116.908315ms","start":"2026-08-22T11:29:07.236584Z","end":"2026-08-22T11:29:07.353492Z","steps":["trace[1499167855] 'agreement among raft nodes before linearized reading' (duration: 116.043908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:07.712198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.767599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:07.712288Z","caller":"traceutil/trace.go:171","msg":"trace[1318304130] linearizableReadLoop","detail":"{readStateIndex:30516; appliedIndex:30514; }","duration":"346.467181ms","start":"2026-08-22T11:29:07.365805Z","end":"2026-08-22T11:29:07.712273Z","steps":["trace[1318304130] 'read index received' (duration: 67.760915ms)","trace[1318304130] 'applied index is now lower than readState.Index' (duration: 278.705216ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:07.712458Z","caller":"traceutil/trace.go:171","msg":"trace[1956147903] transaction","detail":"{read_only:false; response_revision:29177; number_of_response:1; }","duration":"354.8111ms","start":"2026-08-22T11:29:07.357634Z","end":"2026-08-22T11:29:07.712446Z","steps":["trace[1956147903] 'process raft request' (duration: 201.705195ms)","trace[1956147903] 'compare' (duration: 152.650597ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:07.712503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:07.357626Z","time spent":"354.854881ms","remote":"127.0.0.1:55400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:07.712786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.515401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"warn","ts":"2026-08-22T11:29:07.712867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.325459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:29:07.712894Z","caller":"traceutil/trace.go:171","msg":"trace[468633987] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:29177; }","duration":"166.39988ms","start":"2026-08-22T11:29:07.546486Z","end":"2026-08-22T11:29:07.712886Z","steps":["trace[468633987] 'agreement among raft nodes before linearized reading' (duration: 166.311889ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:07.712908Z","caller":"traceutil/trace.go:171","msg":"trace[1518868171] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29177; }","duration":"346.671795ms","start":"2026-08-22T11:29:07.366193Z","end":"2026-08-22T11:29:07.712865Z","steps":["trace[1518868171] 'agreement among raft nodes before linearized reading' (duration: 346.358228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:07.712895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.135035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:07.712957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.138684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-22T11:29:07.712976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:07.366187Z","time spent":"346.775128ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-08-22T11:29:07.713014Z","caller":"traceutil/trace.go:171","msg":"trace[931345525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29177; }","duration":"110.320198ms","start":"2026-08-22T11:29:07.602668Z","end":"2026-08-22T11:29:07.712988Z","steps":["trace[931345525] 'agreement among raft nodes before linearized reading' (duration: 110.106703ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:07.713025Z","caller":"traceutil/trace.go:171","msg":"trace[219202273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29177; }","duration":"347.231966ms","start":"2026-08-22T11:29:07.365773Z","end":"2026-08-22T11:29:07.713005Z","steps":["trace[219202273] 'agreement among raft nodes before linearized reading' (duration: 347.021492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:07.71307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:07.365757Z","time spent":"347.305078ms","remote":"127.0.0.1:55582","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-08-22T11:29:08.076439Z","caller":"traceutil/trace.go:171","msg":"trace[1898349419] linearizableReadLoop","detail":"{readStateIndex:30519; appliedIndex:30517; }","duration":"225.468668ms","start":"2026-08-22T11:29:07.850944Z","end":"2026-08-22T11:29:08.076413Z","steps":["trace[1898349419] 'read index received' (duration: 225.316505ms)","trace[1898349419] 'applied index is now lower than readState.Index' (duration: 150.743µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:08.076493Z","caller":"traceutil/trace.go:171","msg":"trace[1927138475] transaction","detail":"{read_only:false; response_revision:29179; number_of_response:1; }","duration":"358.518012ms","start":"2026-08-22T11:29:07.717932Z","end":"2026-08-22T11:29:08.07645Z","steps":["trace[1927138475] 'process raft request' (duration: 358.271167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:08.076581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.607882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:08.076627Z","caller":"traceutil/trace.go:171","msg":"trace[328575515] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:29180; }","duration":"225.707633ms","start":"2026-08-22T11:29:07.850907Z","end":"2026-08-22T11:29:08.076615Z","steps":["trace[328575515] 'agreement among raft nodes before linearized reading' (duration: 225.604721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:08.076704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:07.717897Z","time spent":"358.677814ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:08.0767Z","caller":"traceutil/trace.go:171","msg":"trace[1895131414] transaction","detail":"{read_only:false; response_revision:29180; number_of_response:1; }","duration":"357.439431ms","start":"2026-08-22T11:29:07.719203Z","end":"2026-08-22T11:29:08.076643Z","steps":["trace[1895131414] 'process raft request' (duration: 357.157195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:08.076921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:07.71918Z","time spent":"357.655476ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:08.267113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.692815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:08.26739Z","caller":"traceutil/trace.go:171","msg":"trace[730390810] transaction","detail":"{read_only:false; response_revision:29181; number_of_response:1; }","duration":"275.389563ms","start":"2026-08-22T11:29:07.991963Z","end":"2026-08-22T11:29:08.267353Z","steps":["trace[730390810] 'process raft request' (duration: 130.370141ms)","trace[730390810] 'compare' (duration: 144.554282ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:08.564546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.797429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:08.564838Z","caller":"traceutil/trace.go:171","msg":"trace[1994208193] transaction","detail":"{read_only:false; response_revision:29183; number_of_response:1; }","duration":"288.226068ms","start":"2026-08-22T11:29:08.276555Z","end":"2026-08-22T11:29:08.564781Z","steps":["trace[1994208193] 'process raft request' (duration: 134.103723ms)","trace[1994208193] 'compare' (duration: 153.671947ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:08.887667Z","caller":"traceutil/trace.go:171","msg":"trace[1652267010] transaction","detail":"{read_only:false; response_revision:29185; number_of_response:1; }","duration":"328.434557ms","start":"2026-08-22T11:29:08.559195Z","end":"2026-08-22T11:29:08.88763Z","steps":["trace[1652267010] 'process raft request' (duration: 315.02113ms)","trace[1652267010] 'compare' (duration: 12.953638ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:08.88789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:08.559177Z","time spent":"328.58866ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:08.887993Z","caller":"traceutil/trace.go:171","msg":"trace[2136611186] linearizableReadLoop","detail":"{readStateIndex:30526; appliedIndex:30522; }","duration":"319.066098ms","start":"2026-08-22T11:29:08.5689Z","end":"2026-08-22T11:29:08.887966Z","steps":["trace[2136611186] 'read index received' (duration: 25.952516ms)","trace[2136611186] 'applied index is now lower than readState.Index' (duration: 293.112752ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:08.888142Z","caller":"traceutil/trace.go:171","msg":"trace[1754268985] transaction","detail":"{read_only:false; response_revision:29187; number_of_response:1; }","duration":"328.031439ms","start":"2026-08-22T11:29:08.560096Z","end":"2026-08-22T11:29:08.888128Z","steps":["trace[1754268985] 'process raft request' (duration: 327.727694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:08.888207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:08.560084Z","time spent":"328.08896ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:08.888321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.389234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-22T11:29:08.888335Z","caller":"traceutil/trace.go:171","msg":"trace[687447231] transaction","detail":"{read_only:false; response_revision:29186; number_of_response:1; }","duration":"328.699522ms","start":"2026-08-22T11:29:08.559626Z","end":"2026-08-22T11:29:08.888325Z","steps":["trace[687447231] 'process raft request' (duration: 327.942248ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:08.888356Z","caller":"traceutil/trace.go:171","msg":"trace[1036361903] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29187; }","duration":"319.484115ms","start":"2026-08-22T11:29:08.568857Z","end":"2026-08-22T11:29:08.888341Z","steps":["trace[1036361903] 'agreement among raft nodes before linearized reading' (duration: 319.265831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:08.888385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:08.56884Z","time spent":"319.538157ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T11:29:08.888389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:08.559614Z","time spent":"328.746943ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:08.888521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.623831ms","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-08-22T11:29:08.888547Z","caller":"traceutil/trace.go:171","msg":"trace[205445718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29187; }","duration":"316.675522ms","start":"2026-08-22T11:29:08.571866Z","end":"2026-08-22T11:29:08.888541Z","steps":["trace[205445718] 'agreement among raft nodes before linearized reading' (duration: 316.614671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:08.888563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:08.571856Z","time spent":"316.704532ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T11:29:08.888623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.376116ms","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-08-22T11:29:08.88866Z","caller":"traceutil/trace.go:171","msg":"trace[981135050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29187; }","duration":"316.439438ms","start":"2026-08-22T11:29:08.57221Z","end":"2026-08-22T11:29:08.888649Z","steps":["trace[981135050] 'agreement among raft nodes before linearized reading' (duration: 316.277975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:08.88869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:08.572196Z","time spent":"316.486938ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T11:29:09.163693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.152588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:09.163958Z","caller":"traceutil/trace.go:171","msg":"trace[1370583608] linearizableReadLoop","detail":"{readStateIndex:30529; appliedIndex:30526; }","duration":"248.161782ms","start":"2026-08-22T11:29:08.915775Z","end":"2026-08-22T11:29:09.163937Z","steps":["trace[1370583608] 'read index received' (duration: 70.727141ms)","trace[1370583608] 'applied index is now lower than readState.Index' (duration: 177.433361ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:09.163968Z","caller":"traceutil/trace.go:171","msg":"trace[2030567767] transaction","detail":"{read_only:false; response_revision:29189; number_of_response:1; }","duration":"269.229395ms","start":"2026-08-22T11:29:08.894694Z","end":"2026-08-22T11:29:09.163923Z","steps":["trace[2030567767] 'process raft request' (duration: 140.144878ms)","trace[2030567767] 'compare' (duration: 127.952115ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:09.164098Z","caller":"traceutil/trace.go:171","msg":"trace[1800705390] transaction","detail":"{read_only:false; response_revision:29190; number_of_response:1; }","duration":"269.243045ms","start":"2026-08-22T11:29:08.894825Z","end":"2026-08-22T11:29:09.164068Z","steps":["trace[1800705390] 'process raft request' (duration: 269.027991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:09.1643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.529599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T11:29:09.164901Z","caller":"traceutil/trace.go:171","msg":"trace[1131639338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29190; }","duration":"249.157102ms","start":"2026-08-22T11:29:08.91573Z","end":"2026-08-22T11:29:09.164887Z","steps":["trace[1131639338] 'agreement among raft nodes before linearized reading' (duration: 248.338025ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:09.445653Z","caller":"traceutil/trace.go:171","msg":"trace[446307868] linearizableReadLoop","detail":"{readStateIndex:30530; appliedIndex:30529; }","duration":"275.183639ms","start":"2026-08-22T11:29:09.170423Z","end":"2026-08-22T11:29:09.445607Z","steps":["trace[446307868] 'read index received' (duration: 268.093783ms)","trace[446307868] 'applied index is now lower than readState.Index' (duration: 7.088106ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:09.445797Z","caller":"traceutil/trace.go:171","msg":"trace[933565420] transaction","detail":"{read_only:false; response_revision:29191; number_of_response:1; }","duration":"277.555184ms","start":"2026-08-22T11:29:09.168228Z","end":"2026-08-22T11:29:09.445783Z","steps":["trace[933565420] 'process raft request' (duration: 270.342006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:09.445859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.414433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:09.445935Z","caller":"traceutil/trace.go:171","msg":"trace[150011109] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:29191; }","duration":"275.550315ms","start":"2026-08-22T11:29:09.170368Z","end":"2026-08-22T11:29:09.445918Z","steps":["trace[150011109] 'agreement among raft nodes before linearized reading' (duration: 275.419653ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:09.540215Z","caller":"traceutil/trace.go:171","msg":"trace[908573037] transaction","detail":"{read_only:false; response_revision:29192; number_of_response:1; }","duration":"180.835887ms","start":"2026-08-22T11:29:09.359335Z","end":"2026-08-22T11:29:09.540171Z","steps":["trace[908573037] 'process raft request' (duration: 180.604831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:09.541167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.603772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:09.541274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.888248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-22T11:29:09.541265Z","caller":"traceutil/trace.go:171","msg":"trace[1602374974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29192; }","duration":"302.731535ms","start":"2026-08-22T11:29:09.238509Z","end":"2026-08-22T11:29:09.541241Z","steps":["trace[1602374974] 'agreement among raft nodes before linearized reading' (duration: 302.567362ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:09.541391Z","caller":"traceutil/trace.go:171","msg":"trace[651421084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29192; }","duration":"101.065343ms","start":"2026-08-22T11:29:09.440308Z","end":"2026-08-22T11:29:09.541373Z","steps":["trace[651421084] 'agreement among raft nodes before linearized reading' (duration: 100.773166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:09.541376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.203216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-22T11:29:09.541505Z","caller":"traceutil/trace.go:171","msg":"trace[620291315] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:29192; }","duration":"290.418301ms","start":"2026-08-22T11:29:09.251058Z","end":"2026-08-22T11:29:09.541477Z","steps":["trace[620291315] 'agreement among raft nodes before linearized reading' (duration: 290.000832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:09.541488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:09.238496Z","time spent":"302.897539ms","remote":"127.0.0.1:55318","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-08-22T11:29:09.690366Z","caller":"traceutil/trace.go:171","msg":"trace[1313197587] transaction","detail":"{read_only:false; response_revision:29193; number_of_response:1; }","duration":"142.706147ms","start":"2026-08-22T11:29:09.547634Z","end":"2026-08-22T11:29:09.69034Z","steps":["trace[1313197587] 'process raft request' (duration: 112.292696ms)","trace[1313197587] 'compare' (duration: 30.284899ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:09.690365Z","caller":"traceutil/trace.go:171","msg":"trace[305963731] linearizableReadLoop","detail":"{readStateIndex:30532; appliedIndex:30531; }","duration":"140.441083ms","start":"2026-08-22T11:29:09.549895Z","end":"2026-08-22T11:29:09.690336Z","steps":["trace[305963731] 'read index received' (duration: 110.040122ms)","trace[305963731] 'applied index is now lower than readState.Index' (duration: 30.399621ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:09.690484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.563666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:09.690532Z","caller":"traceutil/trace.go:171","msg":"trace[2085636060] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:29193; }","duration":"140.677407ms","start":"2026-08-22T11:29:09.549843Z","end":"2026-08-22T11:29:09.69052Z","steps":["trace[2085636060] 'agreement among raft nodes before linearized reading' (duration: 140.572485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:09.931293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.908226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:09.931406Z","caller":"traceutil/trace.go:171","msg":"trace[990213059] linearizableReadLoop","detail":"{readStateIndex:30533; appliedIndex:30532; }","duration":"179.798116ms","start":"2026-08-22T11:29:09.751591Z","end":"2026-08-22T11:29:09.931389Z","steps":["trace[990213059] 'read index received' (duration: 41.779628ms)","trace[990213059] 'applied index is now lower than readState.Index' (duration: 138.017068ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:09.931519Z","caller":"traceutil/trace.go:171","msg":"trace[1081606047] transaction","detail":"{read_only:false; response_revision:29194; number_of_response:1; }","duration":"186.153748ms","start":"2026-08-22T11:29:09.745352Z","end":"2026-08-22T11:29:09.931506Z","steps":["trace[1081606047] 'process raft request' (duration: 47.929986ms)","trace[1081606047] 'compare' (duration: 137.451998ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:09.932042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.427048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-22T11:29:09.932125Z","caller":"traceutil/trace.go:171","msg":"trace[920765809] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:29194; }","duration":"180.52652ms","start":"2026-08-22T11:29:09.751579Z","end":"2026-08-22T11:29:09.932105Z","steps":["trace[920765809] 'agreement among raft nodes before linearized reading' (duration: 180.287254ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:10.212452Z","caller":"traceutil/trace.go:171","msg":"trace[276054116] transaction","detail":"{read_only:false; response_revision:29195; number_of_response:1; }","duration":"113.690013ms","start":"2026-08-22T11:29:10.098731Z","end":"2026-08-22T11:29:10.212421Z","steps":["trace[276054116] 'process raft request' (duration: 113.478129ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:10.293871Z","caller":"traceutil/trace.go:171","msg":"trace[1529863525] transaction","detail":"{read_only:false; response_revision:29196; number_of_response:1; }","duration":"192.747783ms","start":"2026-08-22T11:29:10.101093Z","end":"2026-08-22T11:29:10.293841Z","steps":["trace[1529863525] 'process raft request' (duration: 192.487709ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:42.346096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26878} {"level":"info","ts":"2026-08-22T11:30:42.500347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26878,"took":"147.845545ms","hash":794259735,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":34750464,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-22T11:30:42.500423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":794259735,"revision":26878,"compact-revision":23364}