{"level":"warn","ts":"2026-10-02T18:51:48.646406Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T18:51:48.646549Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.6:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.6:2380","--initial-cluster=instance=https://162.253.55.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.6:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-02T18:51:48.64665Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T18:51:48.646674Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.6:2380"]} {"level":"info","ts":"2026-10-02T18:51:48.646706Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T18:51:48.647904Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"]} {"level":"info","ts":"2026-10-02T18:51:48.648036Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.6:2380"],"listen-peer-urls":["https://162.253.55.6:2380"],"advertise-client-urls":["https://162.253.55.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.6:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-02T18:51:48.651415Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.027607ms"} {"level":"info","ts":"2026-10-02T18:51:48.6586Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e4145bf1b1a19cd","cluster-id":"e02e9056d18cef61"} {"level":"info","ts":"2026-10-02T18:51:48.658688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T18:51:48.658721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became follower at term 0"} {"level":"info","ts":"2026-10-02T18:51:48.658744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e4145bf1b1a19cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T18:51:48.658761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became follower at term 1"} {"level":"info","ts":"2026-10-02T18:51:48.658821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=(5638864895540337101)"} {"level":"warn","ts":"2026-10-02T18:51:48.665277Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T18:51:48.66739Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T18:51:48.669227Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-02T18:51:48.672731Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e4145bf1b1a19cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T18:51:48.672991Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e4145bf1b1a19cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T18:51:48.673066Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T18:51:48.67321Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T18:51:48.67324Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T18:51:48.674674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=(5638864895540337101)"} {"level":"info","ts":"2026-10-02T18:51:48.675057Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e02e9056d18cef61","local-member-id":"4e4145bf1b1a19cd","added-peer-id":"4e4145bf1b1a19cd","added-peer-peer-urls":["https://162.253.55.6:2380"]} {"level":"info","ts":"2026-10-02T18:51:48.678283Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T18:51:48.678389Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.6:2380"} {"level":"info","ts":"2026-10-02T18:51:48.678455Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.6:2380"} {"level":"info","ts":"2026-10-02T18:51:48.678707Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e4145bf1b1a19cd","initial-advertise-peer-urls":["https://162.253.55.6:2380"],"listen-peer-urls":["https://162.253.55.6:2380"],"advertise-client-urls":["https://162.253.55.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T18:51:48.678755Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T18:51:49.263616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T18:51:49.263713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T18:51:49.263744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd received MsgPreVoteResp from 4e4145bf1b1a19cd at term 1"} {"level":"info","ts":"2026-10-02T18:51:49.263765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became candidate at term 2"} {"level":"info","ts":"2026-10-02T18:51:49.263777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd received MsgVoteResp from 4e4145bf1b1a19cd at term 2"} {"level":"info","ts":"2026-10-02T18:51:49.263797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became leader at term 2"} {"level":"info","ts":"2026-10-02T18:51:49.263823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e4145bf1b1a19cd elected leader 4e4145bf1b1a19cd at term 2"} {"level":"info","ts":"2026-10-02T18:51:49.265507Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T18:51:49.266389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T18:51:49.266384Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e4145bf1b1a19cd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.6:2379]}","request-path":"/0/members/4e4145bf1b1a19cd/attributes","cluster-id":"e02e9056d18cef61","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T18:51:49.266471Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T18:51:49.266794Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T18:51:49.266858Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T18:51:49.266982Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e02e9056d18cef61","local-member-id":"4e4145bf1b1a19cd","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T18:51:49.267134Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T18:51:49.267185Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T18:51:49.269669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.6:2379"} {"level":"info","ts":"2026-10-02T18:51:49.269774Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T18:52:14.592312Z","caller":"traceutil/trace.go:171","msg":"trace[108878855] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"114.027447ms","start":"2026-10-02T18:52:14.478263Z","end":"2026-10-02T18:52:14.59229Z","steps":["trace[108878855] 'process raft request' (duration: 113.925225ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:32.479761Z","caller":"traceutil/trace.go:171","msg":"trace[8957608] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"111.964918ms","start":"2026-10-02T18:52:32.36777Z","end":"2026-10-02T18:52:32.479735Z","steps":["trace[8957608] 'process raft request' (duration: 111.775294ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:32.681901Z","caller":"traceutil/trace.go:171","msg":"trace[183547685] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"156.660069ms","start":"2026-10-02T18:52:32.525214Z","end":"2026-10-02T18:52:32.681874Z","steps":["trace[183547685] 'read index received' (duration: 155.801503ms)","trace[183547685] 'applied index is now lower than readState.Index' (duration: 857.466µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:52:32.681961Z","caller":"traceutil/trace.go:171","msg":"trace[1697264458] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"267.264312ms","start":"2026-10-02T18:52:32.414668Z","end":"2026-10-02T18:52:32.681933Z","steps":["trace[1697264458] 'process raft request' (duration: 266.64379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:52:32.682072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.855583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-10-02T18:52:32.682157Z","caller":"traceutil/trace.go:171","msg":"trace[936556157] range","detail":"{range_begin:/registry/masterleases/162.253.55.6; range_end:; response_count:1; response_revision:589; }","duration":"156.956545ms","start":"2026-10-02T18:52:32.525187Z","end":"2026-10-02T18:52:32.682144Z","steps":["trace[936556157] 'agreement among raft nodes before linearized reading' (duration: 156.821773ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:33.139746Z","caller":"traceutil/trace.go:171","msg":"trace[1667596791] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"375.192694ms","start":"2026-10-02T18:52:32.764525Z","end":"2026-10-02T18:52:33.139717Z","steps":["trace[1667596791] 'process raft request' (duration: 375.144013ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:33.139835Z","caller":"traceutil/trace.go:171","msg":"trace[1134208677] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"445.701451ms","start":"2026-10-02T18:52:32.694086Z","end":"2026-10-02T18:52:33.139787Z","steps":["trace[1134208677] 'process raft request' (duration: 389.572544ms)","trace[1134208677] 'compare' (duration: 55.618877ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:52:33.139939Z","caller":"traceutil/trace.go:171","msg":"trace[897235367] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"437.286773ms","start":"2026-10-02T18:52:32.702626Z","end":"2026-10-02T18:52:33.139913Z","steps":["trace[897235367] 'process raft request' (duration: 436.936937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:52:33.140351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T18:52:32.764493Z","time spent":"375.327876ms","remote":"127.0.0.1:45578","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-10-02T18:52:33.140637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T18:52:32.694055Z","time spent":"445.866794ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T18:52:33.140675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T18:52:32.702592Z","time spent":"437.416305ms","remote":"127.0.0.1:45398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T18:52:48.654449Z","caller":"traceutil/trace.go:171","msg":"trace[485130368] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"101.357686ms","start":"2026-10-02T18:52:48.553069Z","end":"2026-10-02T18:52:48.654427Z","steps":["trace[485130368] 'read index received' (duration: 101.219584ms)","trace[485130368] 'applied index is now lower than readState.Index' (duration: 137.122µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T18:52:48.654639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.54841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-02T18:52:48.654668Z","caller":"traceutil/trace.go:171","msg":"trace[1938491106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"101.612992ms","start":"2026-10-02T18:52:48.553046Z","end":"2026-10-02T18:52:48.654659Z","steps":["trace[1938491106] 'agreement among raft nodes before linearized reading' (duration: 101.49196ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:48.65473Z","caller":"traceutil/trace.go:171","msg":"trace[1968732926] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"112.284664ms","start":"2026-10-02T18:52:48.542417Z","end":"2026-10-02T18:52:48.654702Z","steps":["trace[1968732926] 'process raft request' (duration: 111.872646ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:48.887863Z","caller":"traceutil/trace.go:171","msg":"trace[59318435] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"186.544178ms","start":"2026-10-02T18:52:48.701298Z","end":"2026-10-02T18:52:48.887842Z","steps":["trace[59318435] 'read index received' (duration: 148.098562ms)","trace[59318435] 'applied index is now lower than readState.Index' (duration: 38.444856ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:52:48.887924Z","caller":"traceutil/trace.go:171","msg":"trace[1905448268] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"229.408898ms","start":"2026-10-02T18:52:48.658481Z","end":"2026-10-02T18:52:48.887889Z","steps":["trace[1905448268] 'process raft request' (duration: 190.86076ms)","trace[1905448268] 'compare' (duration: 38.409356ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T18:52:48.888056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.761832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rmf46\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-10-02T18:52:48.888092Z","caller":"traceutil/trace.go:171","msg":"trace[1266465248] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rmf46; range_end:; response_count:1; response_revision:756; }","duration":"186.853024ms","start":"2026-10-02T18:52:48.701226Z","end":"2026-10-02T18:52:48.888079Z","steps":["trace[1266465248] 'agreement among raft nodes before linearized reading' (duration: 186.726602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:52:48.888191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.706638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-02T18:52:48.888289Z","caller":"traceutil/trace.go:171","msg":"trace[205322169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"106.842021ms","start":"2026-10-02T18:52:48.781427Z","end":"2026-10-02T18:52:48.888269Z","steps":["trace[205322169] 'agreement among raft nodes before linearized reading' (duration: 106.605956ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:54:05.112579Z","caller":"traceutil/trace.go:171","msg":"trace[949515065] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1899; }","duration":"109.76601ms","start":"2026-10-02T18:54:05.002789Z","end":"2026-10-02T18:54:05.112555Z","steps":["trace[949515065] 'read index received' (duration: 109.426824ms)","trace[949515065] 'applied index is now lower than readState.Index' (duration: 337.776µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:54:05.112683Z","caller":"traceutil/trace.go:171","msg":"trace[865875967] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"223.216841ms","start":"2026-10-02T18:54:04.88942Z","end":"2026-10-02T18:54:05.112637Z","steps":["trace[865875967] 'process raft request' (duration: 222.910705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:54:05.112793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.991496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-02T18:54:05.112847Z","caller":"traceutil/trace.go:171","msg":"trace[1417840824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1831; }","duration":"110.088867ms","start":"2026-10-02T18:54:05.002746Z","end":"2026-10-02T18:54:05.112835Z","steps":["trace[1417840824] 'agreement among raft nodes before linearized reading' (duration: 109.907613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:54:19.676576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.058088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T18:54:19.676677Z","caller":"traceutil/trace.go:171","msg":"trace[514439303] linearizableReadLoop","detail":"{readStateIndex:2021; appliedIndex:2020; }","duration":"247.362119ms","start":"2026-10-02T18:54:19.429298Z","end":"2026-10-02T18:54:19.67666Z","steps":["trace[514439303] 'read index received' (duration: 61.944684ms)","trace[514439303] 'applied index is now lower than readState.Index' (duration: 185.416415ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:54:19.676719Z","caller":"traceutil/trace.go:171","msg":"trace[1697430571] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"320.794761ms","start":"2026-10-02T18:54:19.355891Z","end":"2026-10-02T18:54:19.676686Z","steps":["trace[1697430571] 'process raft request' (duration: 135.413077ms)","trace[1697430571] 'compare' (duration: 184.945256ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T18:54:19.676803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.510592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-r7677\" ","response":"range_response_count:1 size:5853"} {"level":"info","ts":"2026-10-02T18:54:19.676829Z","caller":"traceutil/trace.go:171","msg":"trace[912672886] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-r7677; range_end:; response_count:1; response_revision:1948; }","duration":"247.561663ms","start":"2026-10-02T18:54:19.429258Z","end":"2026-10-02T18:54:19.676819Z","steps":["trace[912672886] 'agreement among raft nodes before linearized reading' (duration: 247.44479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:54:19.676839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T18:54:19.355868Z","time spent":"320.914453ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T18:54:59.263389Z","caller":"traceutil/trace.go:171","msg":"trace[654540562] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"129.971479ms","start":"2026-10-02T18:54:59.133384Z","end":"2026-10-02T18:54:59.263355Z","steps":["trace[654540562] 'process raft request' (duration: 102.808423ms)","trace[654540562] 'compare' (duration: 26.887881ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:55:36.513427Z","caller":"traceutil/trace.go:171","msg":"trace[2046633200] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"142.590149ms","start":"2026-10-02T18:55:36.370813Z","end":"2026-10-02T18:55:36.513403Z","steps":["trace[2046633200] 'process raft request' (duration: 142.384425ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:56:08.832615Z","caller":"traceutil/trace.go:171","msg":"trace[557677299] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"143.858342ms","start":"2026-10-02T18:56:08.688726Z","end":"2026-10-02T18:56:08.832585Z","steps":["trace[557677299] 'process raft request' (duration: 121.813793ms)","trace[557677299] 'compare' (duration: 21.921846ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T18:56:09.122301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.771904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T18:56:09.122488Z","caller":"traceutil/trace.go:171","msg":"trace[1854726438] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2750; }","duration":"140.024989ms","start":"2026-10-02T18:56:08.982436Z","end":"2026-10-02T18:56:09.122461Z","steps":["trace[1854726438] 'count revisions from in-memory index tree' (duration: 139.622922ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:59:16.14384Z","caller":"traceutil/trace.go:171","msg":"trace[1227391954] transaction","detail":"{read_only:false; response_revision:3941; number_of_response:1; }","duration":"130.0406ms","start":"2026-10-02T18:59:16.01377Z","end":"2026-10-02T18:59:16.14381Z","steps":["trace[1227391954] 'process raft request' (duration: 129.855536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:00:41.162316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.30211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728917"} {"level":"info","ts":"2026-10-02T19:00:41.162423Z","caller":"traceutil/trace.go:171","msg":"trace[1609377046] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5080; }","duration":"131.456674ms","start":"2026-10-02T19:00:41.030943Z","end":"2026-10-02T19:00:41.1624Z","steps":["trace[1609377046] 'range keys from in-memory index tree' (duration: 130.922235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:00:43.714441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.809987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-02T19:00:43.714687Z","caller":"traceutil/trace.go:171","msg":"trace[90727856] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"162.465582ms","start":"2026-10-02T19:00:43.552204Z","end":"2026-10-02T19:00:43.714669Z","steps":["trace[90727856] 'process raft request' (duration: 162.416141ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.714836Z","caller":"traceutil/trace.go:171","msg":"trace[1941138239] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"183.42847ms","start":"2026-10-02T19:00:43.531314Z","end":"2026-10-02T19:00:43.714743Z","steps":["trace[1941138239] 'process raft request' (duration: 28.245555ms)","trace[1941138239] 'compare' (duration: 154.655975ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:00:43.714898Z","caller":"traceutil/trace.go:171","msg":"trace[1183817576] linearizableReadLoop","detail":"{readStateIndex:5318; appliedIndex:5316; }","duration":"171.503023ms","start":"2026-10-02T19:00:43.543375Z","end":"2026-10-02T19:00:43.714878Z","steps":["trace[1183817576] 'read index received' (duration: 16.155736ms)","trace[1183817576] 'applied index is now lower than readState.Index' (duration: 155.345667ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:00:43.714998Z","caller":"traceutil/trace.go:171","msg":"trace[1920419151] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"175.292155ms","start":"2026-10-02T19:00:43.539691Z","end":"2026-10-02T19:00:43.714984Z","steps":["trace[1920419151] 'process raft request' (duration: 174.853697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:00:43.715075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.386553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:00:43.71512Z","caller":"traceutil/trace.go:171","msg":"trace[272510002] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5128; }","duration":"179.468484ms","start":"2026-10-02T19:00:43.535638Z","end":"2026-10-02T19:00:43.715107Z","steps":["trace[272510002] 'agreement among raft nodes before linearized reading' (duration: 179.382552ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.715824Z","caller":"traceutil/trace.go:171","msg":"trace[354055662] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"152.270908ms","start":"2026-10-02T19:00:43.563537Z","end":"2026-10-02T19:00:43.715808Z","steps":["trace[354055662] 'process raft request' (duration: 152.183757ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.715851Z","caller":"traceutil/trace.go:171","msg":"trace[1890166999] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"152.478312ms","start":"2026-10-02T19:00:43.563354Z","end":"2026-10-02T19:00:43.715833Z","steps":["trace[1890166999] 'process raft request' (duration: 152.088224ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.809098Z","caller":"traceutil/trace.go:171","msg":"trace[545557764] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"174.738915ms","start":"2026-10-02T19:00:43.63433Z","end":"2026-10-02T19:00:43.809069Z","steps":["trace[545557764] 'process raft request' (duration: 174.300667ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.80934Z","caller":"traceutil/trace.go:171","msg":"trace[1946740007] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"166.185993ms","start":"2026-10-02T19:00:43.643117Z","end":"2026-10-02T19:00:43.809303Z","steps":["trace[1946740007] 'process raft request' (duration: 165.871457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:00:43.809506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.227795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7dc6aaf2-e07e-4862-a43f-f3d034d90949\" ","response":"range_response_count:1 size:3599"} {"level":"warn","ts":"2026-10-02T19:00:43.80953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.067024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m54cz\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-10-02T19:00:43.809583Z","caller":"traceutil/trace.go:171","msg":"trace[392837019] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m54cz; range_end:; response_count:1; response_revision:5132; }","duration":"240.172336ms","start":"2026-10-02T19:00:43.5694Z","end":"2026-10-02T19:00:43.809572Z","steps":["trace[392837019] 'agreement among raft nodes before linearized reading' (duration: 239.988052ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.809583Z","caller":"traceutil/trace.go:171","msg":"trace[1880596184] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7dc6aaf2-e07e-4862-a43f-f3d034d90949; range_end:; response_count:1; response_revision:5132; }","duration":"164.361608ms","start":"2026-10-02T19:00:43.645204Z","end":"2026-10-02T19:00:43.809565Z","steps":["trace[1880596184] 'agreement among raft nodes before linearized reading' (duration: 164.082072ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.939791Z","caller":"traceutil/trace.go:171","msg":"trace[2027077115] linearizableReadLoop","detail":"{readStateIndex:5324; appliedIndex:5323; }","duration":"130.490435ms","start":"2026-10-02T19:00:43.809254Z","end":"2026-10-02T19:00:43.939744Z","steps":["trace[2027077115] 'read index received' (duration: 110.647478ms)","trace[2027077115] 'applied index is now lower than readState.Index' (duration: 19.840697ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:00:43.93991Z","caller":"traceutil/trace.go:171","msg":"trace[161386025] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"218.24715ms","start":"2026-10-02T19:00:43.721632Z","end":"2026-10-02T19:00:43.93988Z","steps":["trace[161386025] 'process raft request' (duration: 198.309262ms)","trace[161386025] 'compare' (duration: 19.255456ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:00:43.940188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.168785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62960"} {"level":"warn","ts":"2026-10-02T19:00:43.940401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.783023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787098"} {"level":"warn","ts":"2026-10-02T19:00:43.940389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.355132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus.18dacb9219437e1d\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-10-02T19:00:43.940446Z","caller":"traceutil/trace.go:171","msg":"trace[1189685813] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5134; }","duration":"148.879724ms","start":"2026-10-02T19:00:43.791555Z","end":"2026-10-02T19:00:43.940435Z","steps":["trace[1189685813] 'agreement among raft nodes before linearized reading' (duration: 148.357424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:00:43.940443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.440472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:00:43.940266Z","caller":"traceutil/trace.go:171","msg":"trace[121891736] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5134; }","duration":"125.283166ms","start":"2026-10-02T19:00:43.814965Z","end":"2026-10-02T19:00:43.940248Z","steps":["trace[121891736] 'agreement among raft nodes before linearized reading' (duration: 124.999971ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.940467Z","caller":"traceutil/trace.go:171","msg":"trace[53898921] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus.18dacb9219437e1d; range_end:; response_count:1; response_revision:5134; }","duration":"219.442093ms","start":"2026-10-02T19:00:43.721004Z","end":"2026-10-02T19:00:43.940446Z","steps":["trace[53898921] 'agreement among raft nodes before linearized reading' (duration: 219.196268ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:43.940488Z","caller":"traceutil/trace.go:171","msg":"trace[161114546] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5134; }","duration":"104.511883ms","start":"2026-10-02T19:00:43.83596Z","end":"2026-10-02T19:00:43.940472Z","steps":["trace[161114546] 'agreement among raft nodes before linearized reading' (duration: 104.438581ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:46.357619Z","caller":"traceutil/trace.go:171","msg":"trace[1741615343] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5163; }","duration":"194.000971ms","start":"2026-10-02T19:00:46.163592Z","end":"2026-10-02T19:00:46.357593Z","steps":["trace[1741615343] 'process raft request' (duration: 181.415042ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:00:58.615509Z","caller":"traceutil/trace.go:171","msg":"trace[1923362208] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"114.486732ms","start":"2026-10-02T19:00:58.500994Z","end":"2026-10-02T19:00:58.615481Z","steps":["trace[1923362208] 'process raft request' (duration: 113.29554ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:01:49.306444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-10-02T19:01:49.377631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"69.716143ms","hash":2907864481,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":33665024,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-02T19:01:49.3777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2907864481,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-10-02T19:06:49.311964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5999} {"level":"info","ts":"2026-10-02T19:06:49.417246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5999,"took":"103.249428ms","hash":3198380626,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":21827584,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-10-02T19:06:49.417329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3198380626,"revision":5999,"compact-revision":2970} {"level":"warn","ts":"2026-10-02T19:07:45.116679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.0369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T19:07:45.116824Z","caller":"traceutil/trace.go:171","msg":"trace[1381298499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8553; }","duration":"117.257203ms","start":"2026-10-02T19:07:44.999547Z","end":"2026-10-02T19:07:45.116804Z","steps":["trace[1381298499] 'range keys from in-memory index tree' (duration: 116.823355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:07:46.769563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.856966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T19:07:46.769677Z","caller":"traceutil/trace.go:171","msg":"trace[1616831024] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8562; }","duration":"108.011929ms","start":"2026-10-02T19:07:46.661639Z","end":"2026-10-02T19:07:46.769651Z","steps":["trace[1616831024] 'count revisions from in-memory index tree' (duration: 107.742634ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:13.294183Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000236a.wal"} {"level":"info","ts":"2026-10-02T19:08:15.889411Z","caller":"traceutil/trace.go:171","msg":"trace[141601220] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"319.595321ms","start":"2026-10-02T19:08:15.569794Z","end":"2026-10-02T19:08:15.88939Z","steps":["trace[141601220] 'process raft request' (duration: 319.445588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:15.889589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:08:15.569772Z","time spent":"319.735013ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:08:15.891689Z","caller":"traceutil/trace.go:171","msg":"trace[1586671304] linearizableReadLoop","detail":"{readStateIndex:9077; appliedIndex:9075; }","duration":"283.002207ms","start":"2026-10-02T19:08:15.608671Z","end":"2026-10-02T19:08:15.891673Z","steps":["trace[1586671304] 'read index received' (duration: 280.664543ms)","trace[1586671304] 'applied index is now lower than readState.Index' (duration: 2.336624ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:08:15.891862Z","caller":"traceutil/trace.go:171","msg":"trace[1499333315] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"294.660338ms","start":"2026-10-02T19:08:15.597152Z","end":"2026-10-02T19:08:15.891812Z","steps":["trace[1499333315] 'process raft request' (duration: 294.446944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:15.89194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.230931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T19:08:15.892007Z","caller":"traceutil/trace.go:171","msg":"trace[1775308905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8770; }","duration":"283.331013ms","start":"2026-10-02T19:08:15.608638Z","end":"2026-10-02T19:08:15.891969Z","steps":["trace[1775308905] 'agreement among raft nodes before linearized reading' (duration: 283.141109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:15.892032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.628767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-02T19:08:15.892117Z","caller":"traceutil/trace.go:171","msg":"trace[277065429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8770; }","duration":"265.756329ms","start":"2026-10-02T19:08:15.626344Z","end":"2026-10-02T19:08:15.8921Z","steps":["trace[277065429] 'agreement among raft nodes before linearized reading' (duration: 265.502324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:15.892674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.710683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:08:15.892773Z","caller":"traceutil/trace.go:171","msg":"trace[1171854352] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8770; }","duration":"106.876987ms","start":"2026-10-02T19:08:15.785877Z","end":"2026-10-02T19:08:15.892754Z","steps":["trace[1171854352] 'agreement among raft nodes before linearized reading' (duration: 106.138513ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:18.973753Z","caller":"traceutil/trace.go:171","msg":"trace[528848730] transaction","detail":"{read_only:false; response_revision:8787; number_of_response:1; }","duration":"150.096805ms","start":"2026-10-02T19:08:18.823614Z","end":"2026-10-02T19:08:18.973711Z","steps":["trace[528848730] 'process raft request' (duration: 136.267943ms)","trace[528848730] 'compare' (duration: 13.672679ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:08:27.471666Z","caller":"traceutil/trace.go:171","msg":"trace[1919991649] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"104.312468ms","start":"2026-10-02T19:08:27.367321Z","end":"2026-10-02T19:08:27.471634Z","steps":["trace[1919991649] 'process raft request' (duration: 104.047433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:27.982267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.191439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-02T19:08:28.280343Z","caller":"traceutil/trace.go:171","msg":"trace[914639587] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"238.732147ms","start":"2026-10-02T19:08:28.041581Z","end":"2026-10-02T19:08:28.280313Z","steps":["trace[914639587] 'process raft request' (duration: 238.503193ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:28.281415Z","caller":"traceutil/trace.go:171","msg":"trace[1528908723] linearizableReadLoop","detail":"{readStateIndex:9147; appliedIndex:9143; }","duration":"197.669689ms","start":"2026-10-02T19:08:28.083724Z","end":"2026-10-02T19:08:28.281393Z","steps":["trace[1528908723] 'read index received' (duration: 3.1558ms)","trace[1528908723] 'applied index is now lower than readState.Index' (duration: 194.512599ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:08:28.281534Z","caller":"traceutil/trace.go:171","msg":"trace[2059155279] transaction","detail":"{read_only:false; response_revision:8837; number_of_response:1; }","duration":"239.708286ms","start":"2026-10-02T19:08:28.041813Z","end":"2026-10-02T19:08:28.281521Z","steps":["trace[2059155279] 'process raft request' (duration: 239.504472ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:28.281486Z","caller":"traceutil/trace.go:171","msg":"trace[581908756] transaction","detail":"{read_only:false; response_revision:8836; number_of_response:1; }","duration":"239.660835ms","start":"2026-10-02T19:08:28.041793Z","end":"2026-10-02T19:08:28.281454Z","steps":["trace[581908756] 'process raft request' (duration: 238.469402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:28.281694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.954233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:28.281736Z","caller":"traceutil/trace.go:171","msg":"trace[1726902329] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8837; }","duration":"198.067475ms","start":"2026-10-02T19:08:28.083656Z","end":"2026-10-02T19:08:28.281723Z","steps":["trace[1726902329] 'agreement among raft nodes before linearized reading' (duration: 197.980634ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:42.378898Z","caller":"traceutil/trace.go:171","msg":"trace[1662623447] transaction","detail":"{read_only:false; response_revision:8982; number_of_response:1; }","duration":"114.745556ms","start":"2026-10-02T19:08:42.264103Z","end":"2026-10-02T19:08:42.378849Z","steps":["trace[1662623447] 'process raft request' (duration: 114.477801ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:09:52.110338Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e4145bf1b1a19cd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T19:09:52.117726Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-02T19:09:52.11783Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-02T19:09:58.165037Z","caller":"traceutil/trace.go:171","msg":"trace[1084345077] transaction","detail":"{read_only:false; response_revision:9700; number_of_response:1; }","duration":"130.437884ms","start":"2026-10-02T19:09:58.034497Z","end":"2026-10-02T19:09:58.164935Z","steps":["trace[1084345077] 'process raft request' (duration: 130.171869ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:11:49.319442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8079} {"level":"info","ts":"2026-10-02T19:11:49.38891Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8079,"took":"67.816837ms","hash":2422094815,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":28590080,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T19:11:49.388984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2422094815,"revision":8079,"compact-revision":5999} {"level":"warn","ts":"2026-10-02T19:15:36.709772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.836981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-02T19:15:36.70987Z","caller":"traceutil/trace.go:171","msg":"trace[395599456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12130; }","duration":"110.952753ms","start":"2026-10-02T19:15:36.598894Z","end":"2026-10-02T19:15:36.709847Z","steps":["trace[395599456] 'range keys from in-memory index tree' (duration: 110.645967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:15:36.709869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.531257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T19:15:36.709972Z","caller":"traceutil/trace.go:171","msg":"trace[1685254784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12130; }","duration":"128.67737ms","start":"2026-10-02T19:15:36.581271Z","end":"2026-10-02T19:15:36.709948Z","steps":["trace[1685254784] 'range keys from in-memory index tree' (duration: 128.303053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:15:36.709872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.335407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-02T19:15:36.710085Z","caller":"traceutil/trace.go:171","msg":"trace[319810161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12130; }","duration":"124.600032ms","start":"2026-10-02T19:15:36.585458Z","end":"2026-10-02T19:15:36.710058Z","steps":["trace[319810161] 'range keys from in-memory index tree' (duration: 124.131294ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:16:49.32657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10459} {"level":"info","ts":"2026-10-02T19:16:49.422924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10459,"took":"94.543453ms","hash":1075652459,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":26664960,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-02T19:16:49.42303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1075652459,"revision":10459,"compact-revision":8079} {"level":"info","ts":"2026-10-02T19:21:49.332211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12623} {"level":"info","ts":"2026-10-02T19:21:49.411033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12623,"took":"76.893217ms","hash":2125675062,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":27860992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T19:21:49.411095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2125675062,"revision":12623,"compact-revision":10459} {"level":"warn","ts":"2026-10-02T19:22:21.74474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.183031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T19:22:21.744823Z","caller":"traceutil/trace.go:171","msg":"trace[1077223986] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:14826; }","duration":"281.357284ms","start":"2026-10-02T19:22:21.463449Z","end":"2026-10-02T19:22:21.744806Z","steps":["trace[1077223986] 'count revisions from in-memory index tree' (duration: 281.042359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:22:21.744926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.883514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T19:22:21.745072Z","caller":"traceutil/trace.go:171","msg":"trace[215030921] transaction","detail":"{read_only:false; response_revision:14827; number_of_response:1; }","duration":"216.409353ms","start":"2026-10-02T19:22:21.528634Z","end":"2026-10-02T19:22:21.745044Z","steps":["trace[215030921] 'process raft request' (duration: 77.291194ms)","trace[215030921] 'compare' (duration: 138.625039ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:22:21.767539Z","caller":"traceutil/trace.go:171","msg":"trace[1337279728] transaction","detail":"{read_only:false; response_revision:14828; number_of_response:1; }","duration":"192.144613ms","start":"2026-10-02T19:22:21.575298Z","end":"2026-10-02T19:22:21.767443Z","steps":["trace[1337279728] 'process raft request' (duration: 191.96868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:22:21.768301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.10941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T19:22:21.76841Z","caller":"traceutil/trace.go:171","msg":"trace[407117668] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:14828; }","duration":"165.285583ms","start":"2026-10-02T19:22:21.603094Z","end":"2026-10-02T19:22:21.768379Z","steps":["trace[407117668] 'agreement among raft nodes before linearized reading' (duration: 164.392897ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:22:21.767405Z","caller":"traceutil/trace.go:171","msg":"trace[1902405281] linearizableReadLoop","detail":"{readStateIndex:15341; appliedIndex:15339; }","duration":"164.221974ms","start":"2026-10-02T19:22:21.603165Z","end":"2026-10-02T19:22:21.767387Z","steps":["trace[1902405281] 'read index received' (duration: 2.776653ms)","trace[1902405281] 'applied index is now lower than readState.Index' (duration: 161.443931ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:22:21.769646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.314233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-hmd6z\" ","response":"range_response_count:1 size:12356"} {"level":"info","ts":"2026-10-02T19:22:21.769755Z","caller":"traceutil/trace.go:171","msg":"trace[1079238982] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-hmd6z; range_end:; response_count:1; response_revision:14828; }","duration":"118.478496ms","start":"2026-10-02T19:22:21.651251Z","end":"2026-10-02T19:22:21.76973Z","steps":["trace[1079238982] 'agreement among raft nodes before linearized reading' (duration: 118.16665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:22:21.770133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.682848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T19:22:21.770232Z","caller":"traceutil/trace.go:171","msg":"trace[1446738043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14828; }","duration":"101.783429ms","start":"2026-10-02T19:22:21.668404Z","end":"2026-10-02T19:22:21.770188Z","steps":["trace[1446738043] 'agreement among raft nodes before linearized reading' (duration: 101.604366ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:22:33.149378Z","caller":"traceutil/trace.go:171","msg":"trace[681119274] linearizableReadLoop","detail":"{readStateIndex:15522; appliedIndex:15521; }","duration":"148.810931ms","start":"2026-10-02T19:22:33.000522Z","end":"2026-10-02T19:22:33.149333Z","steps":["trace[681119274] 'read index received' (duration: 148.622878ms)","trace[681119274] 'applied index is now lower than readState.Index' (duration: 186.563µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:22:33.14969Z","caller":"traceutil/trace.go:171","msg":"trace[1064031307] transaction","detail":"{read_only:false; response_revision:15005; number_of_response:1; }","duration":"318.059459ms","start":"2026-10-02T19:22:32.831617Z","end":"2026-10-02T19:22:33.149677Z","steps":["trace[1064031307] 'process raft request' (duration: 317.554359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:22:33.149809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:22:32.831593Z","time spent":"318.132151ms","remote":"127.0.0.1:45398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3710,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:22:33.149989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.421257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-02T19:22:33.15003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.003952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-02T19:22:33.150077Z","caller":"traceutil/trace.go:171","msg":"trace[2054065750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15005; }","duration":"123.063923ms","start":"2026-10-02T19:22:33.027001Z","end":"2026-10-02T19:22:33.150065Z","steps":["trace[2054065750] 'agreement among raft nodes before linearized reading' (duration: 122.92162ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:22:33.150089Z","caller":"traceutil/trace.go:171","msg":"trace[599923681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15005; }","duration":"126.55645ms","start":"2026-10-02T19:22:33.023507Z","end":"2026-10-02T19:22:33.150064Z","steps":["trace[599923681] 'agreement among raft nodes before linearized reading' (duration: 126.273604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:22:33.150177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.630547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-02T19:22:33.150298Z","caller":"traceutil/trace.go:171","msg":"trace[1895135068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15005; }","duration":"149.82197ms","start":"2026-10-02T19:22:33.000446Z","end":"2026-10-02T19:22:33.150268Z","steps":["trace[1895135068] 'agreement among raft nodes before linearized reading' (duration: 149.468004ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:24:14.267842Z","caller":"traceutil/trace.go:171","msg":"trace[1408368643] transaction","detail":"{read_only:false; response_revision:16101; number_of_response:1; }","duration":"127.955555ms","start":"2026-10-02T19:24:14.139856Z","end":"2026-10-02T19:24:14.267812Z","steps":["trace[1408368643] 'process raft request' (duration: 120.930283ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:24:14.269407Z","caller":"traceutil/trace.go:171","msg":"trace[489875367] transaction","detail":"{read_only:false; response_revision:16102; number_of_response:1; }","duration":"112.672307ms","start":"2026-10-02T19:24:14.156705Z","end":"2026-10-02T19:24:14.269377Z","steps":["trace[489875367] 'process raft request' (duration: 112.436712ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:24:14.649961Z","caller":"traceutil/trace.go:171","msg":"trace[1098863914] transaction","detail":"{read_only:false; response_revision:16104; number_of_response:1; }","duration":"179.329529ms","start":"2026-10-02T19:24:14.470604Z","end":"2026-10-02T19:24:14.649934Z","steps":["trace[1098863914] 'process raft request' (duration: 179.164126ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:26:49.339467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14649} {"level":"info","ts":"2026-10-02T19:26:49.41975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14649,"took":"77.51107ms","hash":2876042210,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":31977472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T19:26:49.419826Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2876042210,"revision":14649,"compact-revision":12623} {"level":"info","ts":"2026-10-02T19:29:58.515702Z","caller":"traceutil/trace.go:171","msg":"trace[365881877] transaction","detail":"{read_only:false; response_revision:18618; number_of_response:1; }","duration":"116.339354ms","start":"2026-10-02T19:29:58.399321Z","end":"2026-10-02T19:29:58.515661Z","steps":["trace[365881877] 'process raft request' (duration: 116.10614ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:29:59.085376Z","caller":"traceutil/trace.go:171","msg":"trace[1469264439] transaction","detail":"{read_only:false; response_revision:18619; number_of_response:1; }","duration":"215.80353ms","start":"2026-10-02T19:29:58.869525Z","end":"2026-10-02T19:29:59.085329Z","steps":["trace[1469264439] 'process raft request' (duration: 215.328911ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:30:15.929067Z","caller":"traceutil/trace.go:171","msg":"trace[822337430] linearizableReadLoop","detail":"{readStateIndex:19511; appliedIndex:19510; }","duration":"107.531327ms","start":"2026-10-02T19:30:15.821505Z","end":"2026-10-02T19:30:15.929037Z","steps":["trace[822337430] 'read index received' (duration: 107.370294ms)","trace[822337430] 'applied index is now lower than readState.Index' (duration: 159.753µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:30:15.92937Z","caller":"traceutil/trace.go:171","msg":"trace[962042602] transaction","detail":"{read_only:false; response_revision:18868; number_of_response:1; }","duration":"108.330063ms","start":"2026-10-02T19:30:15.821024Z","end":"2026-10-02T19:30:15.929354Z","steps":["trace[962042602] 'process raft request' (duration: 107.834273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:30:15.929739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.20287ms","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-10-02T19:30:15.929795Z","caller":"traceutil/trace.go:171","msg":"trace[13316404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18868; }","duration":"108.304123ms","start":"2026-10-02T19:30:15.82148Z","end":"2026-10-02T19:30:15.929784Z","steps":["trace[13316404] 'agreement among raft nodes before linearized reading' (duration: 108.087749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:30:15.936887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.974722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-02T19:30:15.93695Z","caller":"traceutil/trace.go:171","msg":"trace[1484635346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18869; }","duration":"112.058144ms","start":"2026-10-02T19:30:15.824875Z","end":"2026-10-02T19:30:15.936933Z","steps":["trace[1484635346] 'agreement among raft nodes before linearized reading' (duration: 111.88215ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:30:15.937201Z","caller":"traceutil/trace.go:171","msg":"trace[1483572395] transaction","detail":"{read_only:false; response_revision:18869; number_of_response:1; }","duration":"103.022943ms","start":"2026-10-02T19:30:15.834166Z","end":"2026-10-02T19:30:15.937189Z","steps":["trace[1483572395] 'process raft request' (duration: 102.487213ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:30:59.310934Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e4145bf1b1a19cd","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T19:30:59.314847Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-02T19:30:59.314911Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-02T19:31:49.349834Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17229} {"level":"info","ts":"2026-10-02T19:31:49.449649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17229,"took":"95.728884ms","hash":970762606,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":33861632,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-02T19:31:49.449718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":970762606,"revision":17229,"compact-revision":14649} {"level":"info","ts":"2026-10-02T19:36:04.417161Z","caller":"traceutil/trace.go:171","msg":"trace[2079721339] transaction","detail":"{read_only:false; response_revision:21430; number_of_response:1; }","duration":"101.647987ms","start":"2026-10-02T19:36:04.315479Z","end":"2026-10-02T19:36:04.417127Z","steps":["trace[2079721339] 'process raft request' (duration: 101.468943ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:36:04.553069Z","caller":"traceutil/trace.go:171","msg":"trace[998499369] transaction","detail":"{read_only:false; response_revision:21431; number_of_response:1; }","duration":"166.716819ms","start":"2026-10-02T19:36:04.386298Z","end":"2026-10-02T19:36:04.553015Z","steps":["trace[998499369] 'process raft request' (duration: 166.339262ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:36:20.773967Z","caller":"traceutil/trace.go:171","msg":"trace[1772141312] transaction","detail":"{read_only:false; response_revision:21511; number_of_response:1; }","duration":"273.046006ms","start":"2026-10-02T19:36:20.500891Z","end":"2026-10-02T19:36:20.773937Z","steps":["trace[1772141312] 'process raft request' (duration: 272.888103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:36:20.989146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.023729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:36:20.989303Z","caller":"traceutil/trace.go:171","msg":"trace[202866925] transaction","detail":"{read_only:false; response_revision:21513; number_of_response:1; }","duration":"295.325258ms","start":"2026-10-02T19:36:20.693958Z","end":"2026-10-02T19:36:20.989284Z","steps":["trace[202866925] 'process raft request' (duration: 295.267556ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:36:20.989526Z","caller":"traceutil/trace.go:171","msg":"trace[2067461929] transaction","detail":"{read_only:false; response_revision:21512; number_of_response:1; }","duration":"374.741523ms","start":"2026-10-02T19:36:20.614713Z","end":"2026-10-02T19:36:20.989454Z","steps":["trace[2067461929] 'process raft request' (duration: 239.332436ms)","trace[2067461929] 'compare' (duration: 134.909607ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:36:20.989628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:36:20.614688Z","time spent":"374.905186ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:36:49.357221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19706} {"level":"info","ts":"2026-10-02T19:36:49.47363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19706,"took":"112.801288ms","hash":3061262176,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":29216768,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T19:36:49.473699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3061262176,"revision":19706,"compact-revision":17229} {"level":"info","ts":"2026-10-02T19:40:31.215415Z","caller":"traceutil/trace.go:171","msg":"trace[12803317] transaction","detail":"{read_only:false; response_revision:24069; number_of_response:1; }","duration":"124.421799ms","start":"2026-10-02T19:40:31.090953Z","end":"2026-10-02T19:40:31.215375Z","steps":["trace[12803317] 'process raft request' (duration: 124.212405ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:40:31.511367Z","caller":"traceutil/trace.go:171","msg":"trace[1200278894] transaction","detail":"{read_only:false; response_revision:24070; number_of_response:1; }","duration":"211.413107ms","start":"2026-10-02T19:40:31.299917Z","end":"2026-10-02T19:40:31.51133Z","steps":["trace[1200278894] 'process raft request' (duration: 174.048759ms)","trace[1200278894] 'compare' (duration: 36.631314ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:40:31.51199Z","caller":"traceutil/trace.go:171","msg":"trace[1314988689] linearizableReadLoop","detail":"{readStateIndex:24880; appliedIndex:24878; }","duration":"179.383621ms","start":"2026-10-02T19:40:31.332592Z","end":"2026-10-02T19:40:31.511976Z","steps":["trace[1314988689] 'read index received' (duration: 141.443841ms)","trace[1314988689] 'applied index is now lower than readState.Index' (duration: 37.93874ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:40:31.512234Z","caller":"traceutil/trace.go:171","msg":"trace[422689638] transaction","detail":"{read_only:false; response_revision:24071; number_of_response:1; }","duration":"212.08751ms","start":"2026-10-02T19:40:31.299928Z","end":"2026-10-02T19:40:31.512015Z","steps":["trace[422689638] 'process raft request' (duration: 211.843685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:40:31.512475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.817334ms","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-10-02T19:40:31.512584Z","caller":"traceutil/trace.go:171","msg":"trace[532656244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24072; }","duration":"175.932216ms","start":"2026-10-02T19:40:31.336606Z","end":"2026-10-02T19:40:31.512538Z","steps":["trace[532656244] 'agreement among raft nodes before linearized reading' (duration: 175.724512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:40:31.512599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.957852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-02T19:40:31.512693Z","caller":"traceutil/trace.go:171","msg":"trace[1361214005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24072; }","duration":"180.089804ms","start":"2026-10-02T19:40:31.332585Z","end":"2026-10-02T19:40:31.512675Z","steps":["trace[1361214005] 'agreement among raft nodes before linearized reading' (duration: 179.83961ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:40:31.512195Z","caller":"traceutil/trace.go:171","msg":"trace[1015170219] transaction","detail":"{read_only:false; response_revision:24072; number_of_response:1; }","duration":"138.259912ms","start":"2026-10-02T19:40:31.373923Z","end":"2026-10-02T19:40:31.512183Z","steps":["trace[1015170219] 'process raft request' (duration: 138.007987ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:41:49.363984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21660} {"level":"info","ts":"2026-10-02T19:41:49.466062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21660,"took":"99.464586ms","hash":2162437559,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":43417600,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-02T19:41:49.466115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2162437559,"revision":21660,"compact-revision":19706} {"level":"info","ts":"2026-10-02T19:43:14.213267Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006783.wal"} {"level":"info","ts":"2026-10-02T19:44:04.1392Z","caller":"traceutil/trace.go:171","msg":"trace[757336005] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"154.872036ms","start":"2026-10-02T19:44:03.984301Z","end":"2026-10-02T19:44:04.139173Z","steps":["trace[757336005] 'process raft request' (duration: 154.727963ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:04.156915Z","caller":"traceutil/trace.go:171","msg":"trace[448060467] linearizableReadLoop","detail":"{readStateIndex:27027; appliedIndex:27025; }","duration":"108.929346ms","start":"2026-10-02T19:44:04.047958Z","end":"2026-10-02T19:44:04.156887Z","steps":["trace[448060467] 'read index received' (duration: 91.045507ms)","trace[448060467] 'applied index is now lower than readState.Index' (duration: 17.882769ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:04.156981Z","caller":"traceutil/trace.go:171","msg":"trace[1459774817] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"134.009992ms","start":"2026-10-02T19:44:04.022941Z","end":"2026-10-02T19:44:04.156951Z","steps":["trace[1459774817] 'process raft request' (duration: 133.791557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:04.157155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.17384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-02T19:44:04.15723Z","caller":"traceutil/trace.go:171","msg":"trace[231071002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26163; }","duration":"109.291481ms","start":"2026-10-02T19:44:04.047918Z","end":"2026-10-02T19:44:04.15721Z","steps":["trace[231071002] 'agreement among raft nodes before linearized reading' (duration: 109.071487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:04.501094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.351279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:04.501279Z","caller":"traceutil/trace.go:171","msg":"trace[308185947] linearizableReadLoop","detail":"{readStateIndex:27028; appliedIndex:27027; }","duration":"295.299549ms","start":"2026-10-02T19:44:04.205962Z","end":"2026-10-02T19:44:04.501261Z","steps":["trace[308185947] 'read index received' (duration: 95.720155ms)","trace[308185947] 'applied index is now lower than readState.Index' (duration: 199.577384ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:04.501406Z","caller":"traceutil/trace.go:171","msg":"trace[1593036402] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"340.910113ms","start":"2026-10-02T19:44:04.160479Z","end":"2026-10-02T19:44:04.501389Z","steps":["trace[1593036402] 'process raft request' (duration: 141.162466ms)","trace[1593036402] 'compare' (duration: 199.109845ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:04.501502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:04.160461Z","time spent":"340.999676ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:04.501583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.597023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:04.501777Z","caller":"traceutil/trace.go:171","msg":"trace[220053265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26164; }","duration":"295.787388ms","start":"2026-10-02T19:44:04.205933Z","end":"2026-10-02T19:44:04.50172Z","steps":["trace[220053265] 'agreement among raft nodes before linearized reading' (duration: 295.537393ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:04.800263Z","caller":"traceutil/trace.go:171","msg":"trace[132401617] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"413.642181ms","start":"2026-10-02T19:44:04.386592Z","end":"2026-10-02T19:44:04.800234Z","steps":["trace[132401617] 'process raft request' (duration: 378.659648ms)","trace[132401617] 'compare' (duration: 34.83729ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:04.80041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:04.386566Z","time spent":"413.762394ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:04.858845Z","caller":"traceutil/trace.go:171","msg":"trace[774565427] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"422.710583ms","start":"2026-10-02T19:44:04.436098Z","end":"2026-10-02T19:44:04.858809Z","steps":["trace[774565427] 'process raft request' (duration: 422.398637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:04.858975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:04.436069Z","time spent":"422.835385ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:04.859064Z","caller":"traceutil/trace.go:171","msg":"trace[1289602330] linearizableReadLoop","detail":"{readStateIndex:27031; appliedIndex:27028; }","duration":"357.703731ms","start":"2026-10-02T19:44:04.501329Z","end":"2026-10-02T19:44:04.859033Z","steps":["trace[1289602330] 'read index received' (duration: 263.886042ms)","trace[1289602330] 'applied index is now lower than readState.Index' (duration: 93.816749ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:04.859281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.999571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:44:04.859343Z","caller":"traceutil/trace.go:171","msg":"trace[333723462] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26168; }","duration":"584.077863ms","start":"2026-10-02T19:44:04.275223Z","end":"2026-10-02T19:44:04.859301Z","steps":["trace[333723462] 'agreement among raft nodes before linearized reading' (duration: 584.018522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:04.859365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:04.275196Z","time spent":"584.164094ms","remote":"127.0.0.1:45478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:44:04.859372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.308685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:04.859418Z","caller":"traceutil/trace.go:171","msg":"trace[1208133028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26168; }","duration":"356.440017ms","start":"2026-10-02T19:44:04.502965Z","end":"2026-10-02T19:44:04.859405Z","steps":["trace[1208133028] 'agreement among raft nodes before linearized reading' (duration: 356.356505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:04.859477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:04.502894Z","time spent":"356.55077ms","remote":"127.0.0.1:45216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-02T19:44:04.859483Z","caller":"traceutil/trace.go:171","msg":"trace[1092629834] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"409.075965ms","start":"2026-10-02T19:44:04.450399Z","end":"2026-10-02T19:44:04.859475Z","steps":["trace[1092629834] 'process raft request' (duration: 408.26462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:04.859691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.031577ms","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-10-02T19:44:04.859726Z","caller":"traceutil/trace.go:171","msg":"trace[208287776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26168; }","duration":"166.104489ms","start":"2026-10-02T19:44:04.693611Z","end":"2026-10-02T19:44:04.859715Z","steps":["trace[208287776] 'agreement among raft nodes before linearized reading' (duration: 165.969596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:04.859736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:04.450386Z","time spent":"409.32736ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:04.859732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.859275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-02T19:44:04.859781Z","caller":"traceutil/trace.go:171","msg":"trace[1419404699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26168; }","duration":"155.971908ms","start":"2026-10-02T19:44:04.703794Z","end":"2026-10-02T19:44:04.859766Z","steps":["trace[1419404699] 'agreement among raft nodes before linearized reading' (duration: 155.827735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:04.859819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.106734ms","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-10-02T19:44:04.859897Z","caller":"traceutil/trace.go:171","msg":"trace[2018924171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26168; }","duration":"257.204575ms","start":"2026-10-02T19:44:04.602676Z","end":"2026-10-02T19:44:04.859881Z","steps":["trace[2018924171] 'agreement among raft nodes before linearized reading' (duration: 256.958291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:05.112162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.051801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:05.112303Z","caller":"traceutil/trace.go:171","msg":"trace[533796128] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"251.150631ms","start":"2026-10-02T19:44:04.861129Z","end":"2026-10-02T19:44:05.112279Z","steps":["trace[533796128] 'process raft request' (duration: 87.860786ms)","trace[533796128] 'compare' (duration: 162.848747ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:05.113519Z","caller":"traceutil/trace.go:171","msg":"trace[1477614634] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"248.406999ms","start":"2026-10-02T19:44:04.865086Z","end":"2026-10-02T19:44:05.113493Z","steps":["trace[1477614634] 'process raft request' (duration: 248.359728ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:05.113705Z","caller":"traceutil/trace.go:171","msg":"trace[685074473] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"249.749554ms","start":"2026-10-02T19:44:04.863934Z","end":"2026-10-02T19:44:05.113683Z","steps":["trace[685074473] 'process raft request' (duration: 249.322096ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:05.113814Z","caller":"traceutil/trace.go:171","msg":"trace[888569113] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"249.54255ms","start":"2026-10-02T19:44:04.864179Z","end":"2026-10-02T19:44:05.113722Z","steps":["trace[888569113] 'process raft request' (duration: 249.221054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:05.420072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:05.420471Z","caller":"traceutil/trace.go:171","msg":"trace[490888421] linearizableReadLoop","detail":"{readStateIndex:27039; appliedIndex:27036; }","duration":"283.279551ms","start":"2026-10-02T19:44:05.137174Z","end":"2026-10-02T19:44:05.420454Z","steps":["trace[490888421] 'read index received' (duration: 141.906841ms)","trace[490888421] 'applied index is now lower than readState.Index' (duration: 141.37178ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:05.42045Z","caller":"traceutil/trace.go:171","msg":"trace[220433705] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"376.14367ms","start":"2026-10-02T19:44:05.044271Z","end":"2026-10-02T19:44:05.420415Z","steps":["trace[220433705] 'process raft request' (duration: 235.330001ms)","trace[220433705] 'compare' (duration: 140.157556ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:05.420688Z","caller":"traceutil/trace.go:171","msg":"trace[1086966402] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"375.972447ms","start":"2026-10-02T19:44:05.044621Z","end":"2026-10-02T19:44:05.420593Z","steps":["trace[1086966402] 'process raft request' (duration: 375.661661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:05.420857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:05.04461Z","time spent":"376.160062ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:05.42087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.679028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-02T19:44:05.420944Z","caller":"traceutil/trace.go:171","msg":"trace[1907351374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26175; }","duration":"283.822361ms","start":"2026-10-02T19:44:05.137107Z","end":"2026-10-02T19:44:05.420929Z","steps":["trace[1907351374] 'agreement among raft nodes before linearized reading' (duration: 283.503165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:05.42105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.340933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:44:05.421351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.424337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-02T19:44:05.421383Z","caller":"traceutil/trace.go:171","msg":"trace[85028708] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26175; }","duration":"193.494798ms","start":"2026-10-02T19:44:05.227878Z","end":"2026-10-02T19:44:05.421373Z","steps":["trace[85028708] 'agreement among raft nodes before linearized reading' (duration: 193.380296ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:05.421378Z","caller":"traceutil/trace.go:171","msg":"trace[1413825592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26175; }","duration":"214.74256ms","start":"2026-10-02T19:44:05.206616Z","end":"2026-10-02T19:44:05.421359Z","steps":["trace[1413825592] 'agreement among raft nodes before linearized reading' (duration: 214.23916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:05.421199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.161516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:44:05.421723Z","caller":"traceutil/trace.go:171","msg":"trace[643775267] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:26175; }","duration":"103.735667ms","start":"2026-10-02T19:44:05.317976Z","end":"2026-10-02T19:44:05.421712Z","steps":["trace[643775267] 'agreement among raft nodes before linearized reading' (duration: 103.160166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:05.421296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:05.044251Z","time spent":"376.501586ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:05.4206Z","caller":"traceutil/trace.go:171","msg":"trace[600630257] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"355.265285ms","start":"2026-10-02T19:44:05.065314Z","end":"2026-10-02T19:44:05.420579Z","steps":["trace[600630257] 'process raft request' (duration: 355.092992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:05.422026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:05.0653Z","time spent":"356.619821ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:05.703701Z","caller":"traceutil/trace.go:171","msg":"trace[1777190388] linearizableReadLoop","detail":"{readStateIndex:27040; appliedIndex:27039; }","duration":"274.351331ms","start":"2026-10-02T19:44:05.429331Z","end":"2026-10-02T19:44:05.703683Z","steps":["trace[1777190388] 'read index received' (duration: 274.139937ms)","trace[1777190388] 'applied index is now lower than readState.Index' (duration: 210.734µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:05.703784Z","caller":"traceutil/trace.go:171","msg":"trace[682083953] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"274.468053ms","start":"2026-10-02T19:44:05.429308Z","end":"2026-10-02T19:44:05.703776Z","steps":["trace[682083953] 'process raft request' (duration: 274.235508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:05.704008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.660364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"warn","ts":"2026-10-02T19:44:05.704036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.704638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-02T19:44:05.704109Z","caller":"traceutil/trace.go:171","msg":"trace[605869912] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26176; }","duration":"181.778206ms","start":"2026-10-02T19:44:05.522307Z","end":"2026-10-02T19:44:05.704085Z","steps":["trace[605869912] 'agreement among raft nodes before linearized reading' (duration: 181.516131ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:05.704129Z","caller":"traceutil/trace.go:171","msg":"trace[2036795950] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26176; }","duration":"274.83961ms","start":"2026-10-02T19:44:05.429271Z","end":"2026-10-02T19:44:05.70411Z","steps":["trace[2036795950] 'agreement among raft nodes before linearized reading' (duration: 274.588216ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:11.237768Z","caller":"traceutil/trace.go:171","msg":"trace[1038774458] linearizableReadLoop","detail":"{readStateIndex:27077; appliedIndex:27076; }","duration":"199.799517ms","start":"2026-10-02T19:44:11.037948Z","end":"2026-10-02T19:44:11.237747Z","steps":["trace[1038774458] 'read index received' (duration: 199.705415ms)","trace[1038774458] 'applied index is now lower than readState.Index' (duration: 93.232µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:11.237875Z","caller":"traceutil/trace.go:171","msg":"trace[896141174] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"204.103429ms","start":"2026-10-02T19:44:11.033761Z","end":"2026-10-02T19:44:11.237864Z","steps":["trace[896141174] 'process raft request' (duration: 203.835254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.237961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.988481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:11.238023Z","caller":"traceutil/trace.go:171","msg":"trace[1705883045] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26212; }","duration":"200.113153ms","start":"2026-10-02T19:44:11.037893Z","end":"2026-10-02T19:44:11.238006Z","steps":["trace[1705883045] 'agreement among raft nodes before linearized reading' (duration: 199.97546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.466823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.217543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:11.466919Z","caller":"traceutil/trace.go:171","msg":"trace[1195764413] linearizableReadLoop","detail":"{readStateIndex:27078; appliedIndex:27077; }","duration":"229.070952ms","start":"2026-10-02T19:44:11.237832Z","end":"2026-10-02T19:44:11.466903Z","steps":["trace[1195764413] 'read index received' (duration: 102.692177ms)","trace[1195764413] 'applied index is now lower than readState.Index' (duration: 126.377875ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:11.466974Z","caller":"traceutil/trace.go:171","msg":"trace[1976444633] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"336.792314ms","start":"2026-10-02T19:44:11.130171Z","end":"2026-10-02T19:44:11.466963Z","steps":["trace[1976444633] 'process raft request' (duration: 210.330837ms)","trace[1976444633] 'compare' (duration: 126.087431ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:11.467022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.130146Z","time spent":"336.846695ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:11.467169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.775242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-02T19:44:11.467199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.131238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-02T19:44:11.467166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.022235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:11.467264Z","caller":"traceutil/trace.go:171","msg":"trace[990066041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26213; }","duration":"330.23087ms","start":"2026-10-02T19:44:11.137013Z","end":"2026-10-02T19:44:11.467244Z","steps":["trace[990066041] 'agreement among raft nodes before linearized reading' (duration: 330.035147ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:11.467271Z","caller":"traceutil/trace.go:171","msg":"trace[1726215688] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26213; }","duration":"274.174228ms","start":"2026-10-02T19:44:11.193078Z","end":"2026-10-02T19:44:11.467252Z","steps":["trace[1726215688] 'agreement among raft nodes before linearized reading' (duration: 274.006514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.467306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.13699Z","time spent":"330.307701ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-02T19:44:11.467323Z","caller":"traceutil/trace.go:171","msg":"trace[676077881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26213; }","duration":"328.891514ms","start":"2026-10-02T19:44:11.138332Z","end":"2026-10-02T19:44:11.467223Z","steps":["trace[676077881] 'agreement among raft nodes before linearized reading' (duration: 328.67134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.467372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.138316Z","time spent":"329.043658ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T19:44:11.467353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.930801ms","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-10-02T19:44:11.46744Z","caller":"traceutil/trace.go:171","msg":"trace[984857743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26213; }","duration":"333.048764ms","start":"2026-10-02T19:44:11.134371Z","end":"2026-10-02T19:44:11.46742Z","steps":["trace[984857743] 'agreement among raft nodes before linearized reading' (duration: 332.799699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.467565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.134361Z","time spent":"333.191796ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T19:44:11.795198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.06376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:11.795315Z","caller":"traceutil/trace.go:171","msg":"trace[1121734062] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"354.064942ms","start":"2026-10-02T19:44:11.44123Z","end":"2026-10-02T19:44:11.795295Z","steps":["trace[1121734062] 'process raft request' (duration: 246.813648ms)","trace[1121734062] 'compare' (duration: 106.940008ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:11.795402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.441211Z","time spent":"354.161433ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:11.796777Z","caller":"traceutil/trace.go:171","msg":"trace[1909143191] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"341.556434ms","start":"2026-10-02T19:44:11.455186Z","end":"2026-10-02T19:44:11.796743Z","steps":["trace[1909143191] 'process raft request' (duration: 341.439612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.796946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.455176Z","time spent":"341.681748ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:11.797052Z","caller":"traceutil/trace.go:171","msg":"trace[1331205350] linearizableReadLoop","detail":"{readStateIndex:27081; appliedIndex:27078; }","duration":"330.089338ms","start":"2026-10-02T19:44:11.46694Z","end":"2026-10-02T19:44:11.79703Z","steps":["trace[1331205350] 'read index received' (duration: 221.119751ms)","trace[1331205350] 'applied index is now lower than readState.Index' (duration: 108.968377ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:11.797097Z","caller":"traceutil/trace.go:171","msg":"trace[410578188] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"322.85833ms","start":"2026-10-02T19:44:11.474209Z","end":"2026-10-02T19:44:11.797067Z","steps":["trace[410578188] 'process raft request' (duration: 322.781389ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:11.797111Z","caller":"traceutil/trace.go:171","msg":"trace[280434355] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"323.788298ms","start":"2026-10-02T19:44:11.473303Z","end":"2026-10-02T19:44:11.797091Z","steps":["trace[280434355] 'process raft request' (duration: 323.626886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.797228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.202582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:44:11.797244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.168791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:44:11.797251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.474181Z","time spent":"322.995883ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:11.797245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.191229ms","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":"warn","ts":"2026-10-02T19:44:11.79727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.473279Z","time spent":"323.91404ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:11.797295Z","caller":"traceutil/trace.go:171","msg":"trace[153160330] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:26219; }","duration":"203.259733ms","start":"2026-10-02T19:44:11.594019Z","end":"2026-10-02T19:44:11.797278Z","steps":["trace[153160330] 'agreement among raft nodes before linearized reading' (duration: 203.169011ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:11.797307Z","caller":"traceutil/trace.go:171","msg":"trace[1958835642] 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:26219; }","duration":"378.2907ms","start":"2026-10-02T19:44:11.419Z","end":"2026-10-02T19:44:11.797291Z","steps":["trace[1958835642] 'agreement among raft nodes before linearized reading' (duration: 378.163108ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:11.797401Z","caller":"traceutil/trace.go:171","msg":"trace[1547564168] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"325.346628ms","start":"2026-10-02T19:44:11.471669Z","end":"2026-10-02T19:44:11.797015Z","steps":["trace[1547564168] 'process raft request' (duration: 324.998851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.797427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.418983Z","time spent":"378.401923ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-10-02T19:44:11.797269Z","caller":"traceutil/trace.go:171","msg":"trace[926400208] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26219; }","duration":"528.304304ms","start":"2026-10-02T19:44:11.268955Z","end":"2026-10-02T19:44:11.797259Z","steps":["trace[926400208] 'agreement among raft nodes before linearized reading' (duration: 528.204523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.797548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.268942Z","time spent":"528.57292ms","remote":"127.0.0.1:45422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-10-02T19:44:11.79679Z","caller":"traceutil/trace.go:171","msg":"trace[550829811] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"345.557731ms","start":"2026-10-02T19:44:11.451169Z","end":"2026-10-02T19:44:11.796727Z","steps":["trace[550829811] 'process raft request' (duration: 345.291996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:11.797947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.451155Z","time spent":"346.690022ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:11.799035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:11.471623Z","time spent":"325.807596ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:12.034864Z","caller":"traceutil/trace.go:171","msg":"trace[1398257554] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"230.136843ms","start":"2026-10-02T19:44:11.804697Z","end":"2026-10-02T19:44:12.034834Z","steps":["trace[1398257554] 'process raft request' (duration: 229.918609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:12.390135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.864118ms","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:6"} {"level":"info","ts":"2026-10-02T19:44:12.39038Z","caller":"traceutil/trace.go:171","msg":"trace[544402967] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26221; }","duration":"239.153214ms","start":"2026-10-02T19:44:12.151195Z","end":"2026-10-02T19:44:12.390348Z","steps":["trace[544402967] 'count revisions from in-memory index tree' (duration: 238.781297ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:12.390614Z","caller":"traceutil/trace.go:171","msg":"trace[1473190038] linearizableReadLoop","detail":"{readStateIndex:27087; appliedIndex:27086; }","duration":"181.755925ms","start":"2026-10-02T19:44:12.208831Z","end":"2026-10-02T19:44:12.390587Z","steps":["trace[1473190038] 'read index received' (duration: 93.577245ms)","trace[1473190038] 'applied index is now lower than readState.Index' (duration: 88.17652ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:12.390639Z","caller":"traceutil/trace.go:171","msg":"trace[2100136369] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"198.211907ms","start":"2026-10-02T19:44:12.192393Z","end":"2026-10-02T19:44:12.390605Z","steps":["trace[2100136369] 'process raft request' (duration: 109.962005ms)","trace[2100136369] 'compare' (duration: 87.706922ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:12.390726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.870298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:12.391342Z","caller":"traceutil/trace.go:171","msg":"trace[505308779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26222; }","duration":"182.52631ms","start":"2026-10-02T19:44:12.208786Z","end":"2026-10-02T19:44:12.391312Z","steps":["trace[505308779] 'agreement among raft nodes before linearized reading' (duration: 181.874418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:12.672099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.385423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-02T19:44:12.672189Z","caller":"traceutil/trace.go:171","msg":"trace[929065828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26222; }","duration":"129.532296ms","start":"2026-10-02T19:44:12.542635Z","end":"2026-10-02T19:44:12.672167Z","steps":["trace[929065828] 'range keys from in-memory index tree' (duration: 129.173819ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:12.809164Z","caller":"traceutil/trace.go:171","msg":"trace[857763051] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"130.32392ms","start":"2026-10-02T19:44:12.678796Z","end":"2026-10-02T19:44:12.80912Z","steps":["trace[857763051] 'process raft request' (duration: 130.092926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:13.128829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.432881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:13.129001Z","caller":"traceutil/trace.go:171","msg":"trace[331536713] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"296.965449ms","start":"2026-10-02T19:44:12.832014Z","end":"2026-10-02T19:44:13.128979Z","steps":["trace[331536713] 'process raft request' (duration: 145.262553ms)","trace[331536713] 'compare' (duration: 151.084774ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:13.13879Z","caller":"traceutil/trace.go:171","msg":"trace[261967585] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"252.631449ms","start":"2026-10-02T19:44:12.886133Z","end":"2026-10-02T19:44:13.138765Z","steps":["trace[261967585] 'process raft request' (duration: 252.492697ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:13.138799Z","caller":"traceutil/trace.go:171","msg":"trace[1968642179] linearizableReadLoop","detail":"{readStateIndex:27091; appliedIndex:27089; }","duration":"262.354563ms","start":"2026-10-02T19:44:12.876418Z","end":"2026-10-02T19:44:13.138772Z","steps":["trace[1968642179] 'read index received' (duration: 100.838402ms)","trace[1968642179] 'applied index is now lower than readState.Index' (duration: 161.514681ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:13.138786Z","caller":"traceutil/trace.go:171","msg":"trace[1440114144] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"298.192403ms","start":"2026-10-02T19:44:12.840556Z","end":"2026-10-02T19:44:13.138748Z","steps":["trace[1440114144] 'process raft request' (duration: 297.892267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:13.139008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.571157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:13.139053Z","caller":"traceutil/trace.go:171","msg":"trace[598622742] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26227; }","duration":"262.67205ms","start":"2026-10-02T19:44:12.876369Z","end":"2026-10-02T19:44:13.139041Z","steps":["trace[598622742] 'agreement among raft nodes before linearized reading' (duration: 262.493686ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:13.13928Z","caller":"traceutil/trace.go:171","msg":"trace[1515153549] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"252.738422ms","start":"2026-10-02T19:44:12.886328Z","end":"2026-10-02T19:44:13.139066Z","steps":["trace[1515153549] 'process raft request' (duration: 252.374715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:13.374166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.667607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:13.374279Z","caller":"traceutil/trace.go:171","msg":"trace[737319598] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26227; }","duration":"111.861281ms","start":"2026-10-02T19:44:13.262392Z","end":"2026-10-02T19:44:13.374253Z","steps":["trace[737319598] 'count revisions from in-memory index tree' (duration: 111.568645ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:13.977185Z","caller":"traceutil/trace.go:171","msg":"trace[1412594828] linearizableReadLoop","detail":"{readStateIndex:27096; appliedIndex:27094; }","duration":"174.324526ms","start":"2026-10-02T19:44:13.80283Z","end":"2026-10-02T19:44:13.977155Z","steps":["trace[1412594828] 'read index received' (duration: 15.042346ms)","trace[1412594828] 'applied index is now lower than readState.Index' (duration: 159.28052ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:13.977601Z","caller":"traceutil/trace.go:171","msg":"trace[61506312] transaction","detail":"{read_only:false; response_revision:26230; number_of_response:1; }","duration":"174.817795ms","start":"2026-10-02T19:44:13.802767Z","end":"2026-10-02T19:44:13.977585Z","steps":["trace[61506312] 'process raft request' (duration: 173.868277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:13.977604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.765884ms","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-10-02T19:44:13.977643Z","caller":"traceutil/trace.go:171","msg":"trace[1098592102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26232; }","duration":"174.875216ms","start":"2026-10-02T19:44:13.802758Z","end":"2026-10-02T19:44:13.977633Z","steps":["trace[1098592102] 'agreement among raft nodes before linearized reading' (duration: 174.660702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:13.977628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.479732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T19:44:13.977713Z","caller":"traceutil/trace.go:171","msg":"trace[294973804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26232; }","duration":"170.612165ms","start":"2026-10-02T19:44:13.807076Z","end":"2026-10-02T19:44:13.977688Z","steps":["trace[294973804] 'agreement among raft nodes before linearized reading' (duration: 170.33759ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:13.977735Z","caller":"traceutil/trace.go:171","msg":"trace[826702509] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"168.600877ms","start":"2026-10-02T19:44:13.808768Z","end":"2026-10-02T19:44:13.977369Z","steps":["trace[826702509] 'process raft request' (duration: 168.335912ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:13.977732Z","caller":"traceutil/trace.go:171","msg":"trace[1956105183] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"174.754113ms","start":"2026-10-02T19:44:13.802951Z","end":"2026-10-02T19:44:13.977705Z","steps":["trace[1956105183] 'process raft request' (duration: 174.099021ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:14.09341Z","caller":"traceutil/trace.go:171","msg":"trace[1451786495] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"112.604644ms","start":"2026-10-02T19:44:13.980781Z","end":"2026-10-02T19:44:14.093386Z","steps":["trace[1451786495] 'process raft request' (duration: 110.742559ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:14.093689Z","caller":"traceutil/trace.go:171","msg":"trace[218175142] transaction","detail":"{read_only:false; response_revision:26234; number_of_response:1; }","duration":"108.868594ms","start":"2026-10-02T19:44:13.984795Z","end":"2026-10-02T19:44:14.093664Z","steps":["trace[218175142] 'process raft request' (duration: 108.549208ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:14.421076Z","caller":"traceutil/trace.go:171","msg":"trace[1328051686] linearizableReadLoop","detail":"{readStateIndex:27102; appliedIndex:27101; }","duration":"213.606469ms","start":"2026-10-02T19:44:14.207442Z","end":"2026-10-02T19:44:14.421049Z","steps":["trace[1328051686] 'read index received' (duration: 213.521818ms)","trace[1328051686] 'applied index is now lower than readState.Index' (duration: 83.561µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:14.421229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.776072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:14.421263Z","caller":"traceutil/trace.go:171","msg":"trace[830203846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26236; }","duration":"213.873554ms","start":"2026-10-02T19:44:14.20738Z","end":"2026-10-02T19:44:14.421254Z","steps":["trace[830203846] 'agreement among raft nodes before linearized reading' (duration: 213.763432ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:14.421605Z","caller":"traceutil/trace.go:171","msg":"trace[1884820882] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"271.571098ms","start":"2026-10-02T19:44:14.150023Z","end":"2026-10-02T19:44:14.421594Z","steps":["trace[1884820882] 'process raft request' (duration: 270.874765ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:14.978698Z","caller":"traceutil/trace.go:171","msg":"trace[711389914] linearizableReadLoop","detail":"{readStateIndex:27104; appliedIndex:27103; }","duration":"109.058917ms","start":"2026-10-02T19:44:14.869612Z","end":"2026-10-02T19:44:14.978671Z","steps":["trace[711389914] 'read index received' (duration: 108.747321ms)","trace[711389914] 'applied index is now lower than readState.Index' (duration: 310.266µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:14.978748Z","caller":"traceutil/trace.go:171","msg":"trace[149876455] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"159.604295ms","start":"2026-10-02T19:44:14.819109Z","end":"2026-10-02T19:44:14.978714Z","steps":["trace[149876455] 'process raft request' (duration: 159.361931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:14.978872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.237401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:14.978949Z","caller":"traceutil/trace.go:171","msg":"trace[1870135521] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26238; }","duration":"109.368623ms","start":"2026-10-02T19:44:14.869561Z","end":"2026-10-02T19:44:14.978929Z","steps":["trace[1870135521] 'agreement among raft nodes before linearized reading' (duration: 109.20891ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:15.202311Z","caller":"traceutil/trace.go:171","msg":"trace[1300943629] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"167.707219ms","start":"2026-10-02T19:44:15.034546Z","end":"2026-10-02T19:44:15.202253Z","steps":["trace[1300943629] 'process raft request' (duration: 98.445276ms)","trace[1300943629] 'compare' (duration: 68.901656ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:15.202339Z","caller":"traceutil/trace.go:171","msg":"trace[436479949] linearizableReadLoop","detail":"{readStateIndex:27105; appliedIndex:27104; }","duration":"131.040334ms","start":"2026-10-02T19:44:15.071279Z","end":"2026-10-02T19:44:15.20232Z","steps":["trace[436479949] 'read index received' (duration: 61.6992ms)","trace[436479949] 'applied index is now lower than readState.Index' (duration: 69.340514ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:15.202518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.207027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:15.202555Z","caller":"traceutil/trace.go:171","msg":"trace[1631675980] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26240; }","duration":"131.31803ms","start":"2026-10-02T19:44:15.071229Z","end":"2026-10-02T19:44:15.202547Z","steps":["trace[1631675980] 'agreement among raft nodes before linearized reading' (duration: 131.223808ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:15.531822Z","caller":"traceutil/trace.go:171","msg":"trace[307864611] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"386.561668ms","start":"2026-10-02T19:44:15.145233Z","end":"2026-10-02T19:44:15.531794Z","steps":["trace[307864611] 'process raft request' (duration: 386.383416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:15.531984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:15.145206Z","time spent":"386.69514ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:15.639908Z","caller":"traceutil/trace.go:171","msg":"trace[557827525] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"494.164549ms","start":"2026-10-02T19:44:15.145715Z","end":"2026-10-02T19:44:15.639879Z","steps":["trace[557827525] 'process raft request' (duration: 438.357471ms)","trace[557827525] 'compare' (duration: 55.607764ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:15.640074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:15.145697Z","time spent":"494.290392ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:15.640063Z","caller":"traceutil/trace.go:171","msg":"trace[557992966] linearizableReadLoop","detail":"{readStateIndex:27108; appliedIndex:27106; }","duration":"437.603296ms","start":"2026-10-02T19:44:15.202433Z","end":"2026-10-02T19:44:15.640036Z","steps":["trace[557992966] 'read index received' (duration: 329.207211ms)","trace[557992966] 'applied index is now lower than readState.Index' (duration: 108.394815ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:15.640231Z","caller":"traceutil/trace.go:171","msg":"trace[743682969] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"122.646645ms","start":"2026-10-02T19:44:15.517571Z","end":"2026-10-02T19:44:15.640218Z","steps":["trace[743682969] 'process raft request' (duration: 122.258258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:15.640395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.262634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:15.640449Z","caller":"traceutil/trace.go:171","msg":"trace[1112357146] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26243; }","duration":"529.372475ms","start":"2026-10-02T19:44:15.111061Z","end":"2026-10-02T19:44:15.640433Z","steps":["trace[1112357146] 'agreement among raft nodes before linearized reading' (duration: 529.272154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:15.640489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:15.111038Z","time spent":"529.439097ms","remote":"127.0.0.1:59758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:44:15.640614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.847697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:15.640652Z","caller":"traceutil/trace.go:171","msg":"trace[1611987417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26243; }","duration":"431.927868ms","start":"2026-10-02T19:44:15.208713Z","end":"2026-10-02T19:44:15.640641Z","steps":["trace[1611987417] 'agreement among raft nodes before linearized reading' (duration: 431.862817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:15.640681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:15.208686Z","time spent":"431.98871ms","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-02T19:44:16.140157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.682349ms","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-10-02T19:44:16.140249Z","caller":"traceutil/trace.go:171","msg":"trace[454061419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26244; }","duration":"157.849173ms","start":"2026-10-02T19:44:15.982378Z","end":"2026-10-02T19:44:16.140227Z","steps":["trace[454061419] 'range keys from in-memory index tree' (duration: 157.487546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:16.140436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.935422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:16.140662Z","caller":"traceutil/trace.go:171","msg":"trace[638710114] linearizableReadLoop","detail":"{readStateIndex:27112; appliedIndex:27110; }","duration":"153.354337ms","start":"2026-10-02T19:44:15.98729Z","end":"2026-10-02T19:44:16.140645Z","steps":["trace[638710114] 'read index received' (duration: 22.136359ms)","trace[638710114] 'applied index is now lower than readState.Index' (duration: 131.216618ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:16.140665Z","caller":"traceutil/trace.go:171","msg":"trace[1404715208] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"157.510126ms","start":"2026-10-02T19:44:15.983135Z","end":"2026-10-02T19:44:16.140645Z","steps":["trace[1404715208] 'process raft request' (duration: 26.285548ms)","trace[1404715208] 'compare' (duration: 130.763449ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:16.140704Z","caller":"traceutil/trace.go:171","msg":"trace[1595375980] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"157.044777ms","start":"2026-10-02T19:44:15.983631Z","end":"2026-10-02T19:44:16.140675Z","steps":["trace[1595375980] 'process raft request' (duration: 156.924265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:16.140807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.49625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:16.140886Z","caller":"traceutil/trace.go:171","msg":"trace[223923461] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26246; }","duration":"153.619862ms","start":"2026-10-02T19:44:15.987249Z","end":"2026-10-02T19:44:16.140869Z","steps":["trace[223923461] 'agreement among raft nodes before linearized reading' (duration: 153.462309ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:26.134229Z","caller":"traceutil/trace.go:171","msg":"trace[257326315] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"461.58952ms","start":"2026-10-02T19:44:25.672606Z","end":"2026-10-02T19:44:26.134196Z","steps":["trace[257326315] 'process raft request' (duration: 452.239983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:26.134368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:25.672581Z","time spent":"461.717273ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:26.135209Z","caller":"traceutil/trace.go:171","msg":"trace[546405891] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"447.666477ms","start":"2026-10-02T19:44:25.687514Z","end":"2026-10-02T19:44:26.13518Z","steps":["trace[546405891] 'process raft request' (duration: 447.513524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:26.135476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:25.687491Z","time spent":"447.786259ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:26.135448Z","caller":"traceutil/trace.go:171","msg":"trace[1266310501] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"251.323875ms","start":"2026-10-02T19:44:25.884101Z","end":"2026-10-02T19:44:26.135425Z","steps":["trace[1266310501] 'process raft request' (duration: 251.018339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:26.69725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1859319233777060866,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-02T19:44:26.940645Z","caller":"traceutil/trace.go:171","msg":"trace[441367620] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"754.612145ms","start":"2026-10-02T19:44:26.186009Z","end":"2026-10-02T19:44:26.940622Z","steps":["trace[441367620] 'process raft request' (duration: 754.426241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:26.940796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.185981Z","time spent":"754.734908ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:27.090416Z","caller":"traceutil/trace.go:171","msg":"trace[615865614] linearizableReadLoop","detail":"{readStateIndex:27188; appliedIndex:27184; }","duration":"893.292204ms","start":"2026-10-02T19:44:26.197095Z","end":"2026-10-02T19:44:27.090387Z","steps":["trace[615865614] 'read index received' (duration: 743.408282ms)","trace[615865614] 'applied index is now lower than readState.Index' (duration: 149.882752ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:27.090794Z","caller":"traceutil/trace.go:171","msg":"trace[1589097065] transaction","detail":"{read_only:false; response_revision:26318; number_of_response:1; }","duration":"903.323896ms","start":"2026-10-02T19:44:26.187443Z","end":"2026-10-02T19:44:27.090767Z","steps":["trace[1589097065] 'process raft request' (duration: 862.064593ms)","trace[1589097065] 'compare' (duration: 40.532999ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:27.090824Z","caller":"traceutil/trace.go:171","msg":"trace[1988490427] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"612.548613ms","start":"2026-10-02T19:44:26.478246Z","end":"2026-10-02T19:44:27.090794Z","steps":["trace[1988490427] 'process raft request' (duration: 612.082874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.090935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.187418Z","time spent":"903.449728ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:27.091024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.478221Z","time spent":"612.683995ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:27.091341Z","caller":"traceutil/trace.go:171","msg":"trace[1653559911] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"848.410814ms","start":"2026-10-02T19:44:26.242913Z","end":"2026-10-02T19:44:27.091324Z","steps":["trace[1653559911] 'process raft request' (duration: 847.303474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.091506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.24289Z","time spent":"848.547167ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:27.092263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.16315ms","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-10-02T19:44:27.092318Z","caller":"traceutil/trace.go:171","msg":"trace[1846008838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26320; }","duration":"895.255501ms","start":"2026-10-02T19:44:26.197049Z","end":"2026-10-02T19:44:27.092304Z","steps":["trace[1846008838] 'agreement among raft nodes before linearized reading' (duration: 894.080599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.092356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.197029Z","time spent":"895.319913ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-02T19:44:27.413501Z","caller":"traceutil/trace.go:171","msg":"trace[42170329] linearizableReadLoop","detail":"{readStateIndex:27189; appliedIndex:27188; }","duration":"322.977682ms","start":"2026-10-02T19:44:27.090499Z","end":"2026-10-02T19:44:27.413477Z","steps":["trace[42170329] 'read index received' (duration: 285.761727ms)","trace[42170329] 'applied index is now lower than readState.Index' (duration: 37.214485ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:27.413649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207723305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:44:27.413665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141902457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-02T19:44:27.41368Z","caller":"traceutil/trace.go:171","msg":"trace[507763795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26321; }","duration":"1.207786146s","start":"2026-10-02T19:44:26.205886Z","end":"2026-10-02T19:44:27.413672Z","steps":["trace[507763795] 'agreement among raft nodes before linearized reading' (duration: 1.207702565s)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:27.413701Z","caller":"traceutil/trace.go:171","msg":"trace[763130006] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26321; }","duration":"1.141962339s","start":"2026-10-02T19:44:26.27173Z","end":"2026-10-02T19:44:27.413692Z","steps":["trace[763130006] 'agreement among raft nodes before linearized reading' (duration: 1.141821966s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.413709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.205876Z","time spent":"1.207826417s","remote":"127.0.0.1:45216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-02T19:44:27.413721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202451445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T19:44:27.413804Z","caller":"traceutil/trace.go:171","msg":"trace[51188171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26321; }","duration":"1.202566128s","start":"2026-10-02T19:44:26.211221Z","end":"2026-10-02T19:44:27.413787Z","steps":["trace[51188171] 'agreement among raft nodes before linearized reading' (duration: 1.202340833s)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:27.41382Z","caller":"traceutil/trace.go:171","msg":"trace[562163187] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"330.172299ms","start":"2026-10-02T19:44:27.083638Z","end":"2026-10-02T19:44:27.41381Z","steps":["trace[562163187] 'process raft request' (duration: 292.609578ms)","trace[562163187] 'compare' (duration: 36.969ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:27.413832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.211212Z","time spent":"1.202615518s","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T19:44:27.413882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.083609Z","time spent":"330.230381ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:27.41395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202886324s","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-10-02T19:44:27.413985Z","caller":"traceutil/trace.go:171","msg":"trace[1627731755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26321; }","duration":"1.202944824s","start":"2026-10-02T19:44:26.211032Z","end":"2026-10-02T19:44:27.413977Z","steps":["trace[1627731755] 'agreement among raft nodes before linearized reading' (duration: 1.202845482s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.413978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.362329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-02T19:44:27.414015Z","caller":"traceutil/trace.go:171","msg":"trace[1773852207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26321; }","duration":"371.431381ms","start":"2026-10-02T19:44:27.042578Z","end":"2026-10-02T19:44:27.41401Z","steps":["trace[1773852207] 'agreement among raft nodes before linearized reading' (duration: 371.36084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.414038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.042561Z","time spent":"371.473041ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-02T19:44:27.413722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.271719Z","time spent":"1.141997689s","remote":"127.0.0.1:45398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-02T19:44:27.414083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203676918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-02T19:44:27.414109Z","caller":"traceutil/trace.go:171","msg":"trace[1956147145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26321; }","duration":"1.20373315s","start":"2026-10-02T19:44:26.210367Z","end":"2026-10-02T19:44:27.4141Z","steps":["trace[1956147145] 'agreement among raft nodes before linearized reading' (duration: 1.203661318s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.414139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.210348Z","time spent":"1.20378471s","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T19:44:27.414156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110313759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:44:27.414177Z","caller":"traceutil/trace.go:171","msg":"trace[1007278926] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:26321; }","duration":"1.110351579s","start":"2026-10-02T19:44:26.303818Z","end":"2026-10-02T19:44:27.414169Z","steps":["trace[1007278926] 'agreement among raft nodes before linearized reading' (duration: 1.110314638s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.414198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.303808Z","time spent":"1.11038535s","remote":"127.0.0.1:37472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:44:27.41399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146726158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-02T19:44:27.414014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.211024Z","time spent":"1.202984345s","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-02T19:44:27.414306Z","caller":"traceutil/trace.go:171","msg":"trace[1560645285] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26321; }","duration":"1.147069615s","start":"2026-10-02T19:44:26.267229Z","end":"2026-10-02T19:44:27.414299Z","steps":["trace[1560645285] 'agreement among raft nodes before linearized reading' (duration: 1.146733088s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.414328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:26.267218Z","time spent":"1.147102806s","remote":"127.0.0.1:33192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":9,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-10-02T19:44:27.649888Z","caller":"traceutil/trace.go:171","msg":"trace[162490273] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"396.746412ms","start":"2026-10-02T19:44:27.253112Z","end":"2026-10-02T19:44:27.649859Z","steps":["trace[162490273] 'process raft request' (duration: 396.630979ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:27.649943Z","caller":"traceutil/trace.go:171","msg":"trace[1238391219] linearizableReadLoop","detail":"{readStateIndex:27191; appliedIndex:27191; }","duration":"230.649734ms","start":"2026-10-02T19:44:27.41926Z","end":"2026-10-02T19:44:27.64991Z","steps":["trace[1238391219] 'read index received' (duration: 230.637773ms)","trace[1238391219] 'applied index is now lower than readState.Index' (duration: 9.65µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:27.650082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.253082Z","time spent":"396.886814ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:27.650337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.054731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-02T19:44:27.650389Z","caller":"traceutil/trace.go:171","msg":"trace[302187159] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26323; }","duration":"231.138413ms","start":"2026-10-02T19:44:27.419237Z","end":"2026-10-02T19:44:27.650376Z","steps":["trace[302187159] 'agreement among raft nodes before linearized reading' (duration: 230.804496ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:27.651362Z","caller":"traceutil/trace.go:171","msg":"trace[1219841822] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"553.891911ms","start":"2026-10-02T19:44:27.097434Z","end":"2026-10-02T19:44:27.651326Z","steps":["trace[1219841822] 'process raft request' (duration: 551.979275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:27.651639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.097418Z","time spent":"554.132875ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:28.235313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.301416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:28.235631Z","caller":"traceutil/trace.go:171","msg":"trace[1884904889] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"816.229374ms","start":"2026-10-02T19:44:27.419375Z","end":"2026-10-02T19:44:28.235604Z","steps":["trace[1884904889] 'process raft request' (duration: 546.543871ms)","trace[1884904889] 'compare' (duration: 268.792836ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:28.235711Z","caller":"traceutil/trace.go:171","msg":"trace[214963576] linearizableReadLoop","detail":"{readStateIndex:27196; appliedIndex:27191; }","duration":"585.679933ms","start":"2026-10-02T19:44:27.650014Z","end":"2026-10-02T19:44:28.235694Z","steps":["trace[214963576] 'read index received' (duration: 315.943989ms)","trace[214963576] 'applied index is now lower than readState.Index' (duration: 269.731714ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:28.235832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.098964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:28.23619Z","caller":"traceutil/trace.go:171","msg":"trace[595871263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26328; }","duration":"814.45388ms","start":"2026-10-02T19:44:27.421723Z","end":"2026-10-02T19:44:28.236176Z","steps":["trace[595871263] 'agreement among raft nodes before linearized reading' (duration: 814.016253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:28.236237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.421697Z","time spent":"814.529342ms","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-02T19:44:28.235834Z","caller":"traceutil/trace.go:171","msg":"trace[1677335846] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"816.172243ms","start":"2026-10-02T19:44:27.419636Z","end":"2026-10-02T19:44:28.235808Z","steps":["trace[1677335846] 'process raft request' (duration: 815.956289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:28.236472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.419629Z","time spent":"816.718873ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:28.235834Z","caller":"traceutil/trace.go:171","msg":"trace[750121311] transaction","detail":"{read_only:false; response_revision:26325; number_of_response:1; }","duration":"816.371037ms","start":"2026-10-02T19:44:27.419436Z","end":"2026-10-02T19:44:28.235807Z","steps":["trace[750121311] 'process raft request' (duration: 815.970769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:28.236614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.419419Z","time spent":"817.155992ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:28.235871Z","caller":"traceutil/trace.go:171","msg":"trace[655981006] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"660.006693ms","start":"2026-10-02T19:44:27.575854Z","end":"2026-10-02T19:44:28.235861Z","steps":["trace[655981006] 'process raft request' (duration: 659.792719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:28.236511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.419365Z","time spent":"816.628932ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:28.235847Z","caller":"traceutil/trace.go:171","msg":"trace[690000935] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"816.301776ms","start":"2026-10-02T19:44:27.419515Z","end":"2026-10-02T19:44:28.235817Z","steps":["trace[690000935] 'process raft request' (duration: 816.00578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:28.236765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.575829Z","time spent":"660.857399ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:44:28.236795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.41951Z","time spent":"817.258474ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:28.301113Z","caller":"traceutil/trace.go:171","msg":"trace[1769494747] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"161.476081ms","start":"2026-10-02T19:44:28.139604Z","end":"2026-10-02T19:44:28.30108Z","steps":["trace[1769494747] 'process raft request' (duration: 161.120534ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:28.301147Z","caller":"traceutil/trace.go:171","msg":"trace[873797810] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"161.236106ms","start":"2026-10-02T19:44:28.139861Z","end":"2026-10-02T19:44:28.301098Z","steps":["trace[873797810] 'process raft request' (duration: 161.096513ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:28.30116Z","caller":"traceutil/trace.go:171","msg":"trace[57956162] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"159.873721ms","start":"2026-10-02T19:44:28.141269Z","end":"2026-10-02T19:44:28.301143Z","steps":["trace[57956162] 'process raft request' (duration: 159.778419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:28.301338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.372434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19229"} {"level":"info","ts":"2026-10-02T19:44:28.301379Z","caller":"traceutil/trace.go:171","msg":"trace[1979961644] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26331; }","duration":"645.458626ms","start":"2026-10-02T19:44:27.655909Z","end":"2026-10-02T19:44:28.301367Z","steps":["trace[1979961644] 'agreement among raft nodes before linearized reading' (duration: 645.281523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:28.301409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:27.655894Z","time spent":"645.507867ms","remote":"127.0.0.1:45388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19252,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-02T19:44:32.81234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.276771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.6\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-02T19:44:32.812422Z","caller":"traceutil/trace.go:171","msg":"trace[1960741541] range","detail":"{range_begin:/registry/masterleases/162.253.55.6; range_end:; response_count:1; response_revision:26418; }","duration":"101.404472ms","start":"2026-10-02T19:44:32.710998Z","end":"2026-10-02T19:44:32.812402Z","steps":["trace[1960741541] 'range keys from in-memory index tree' (duration: 101.043736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:32.812571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.202851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9bf21e0-5ac8-4ada-8521-8f671cfc0075\" ","response":"range_response_count:1 size:3642"} {"level":"info","ts":"2026-10-02T19:44:32.81268Z","caller":"traceutil/trace.go:171","msg":"trace[731862717] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9bf21e0-5ac8-4ada-8521-8f671cfc0075; range_end:; response_count:1; response_revision:26418; }","duration":"195.369665ms","start":"2026-10-02T19:44:32.617271Z","end":"2026-10-02T19:44:32.81264Z","steps":["trace[731862717] 'range keys from in-memory index tree' (duration: 194.823975ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:32.955215Z","caller":"traceutil/trace.go:171","msg":"trace[900187041] linearizableReadLoop","detail":"{readStateIndex:27288; appliedIndex:27288; }","duration":"130.056066ms","start":"2026-10-02T19:44:32.825136Z","end":"2026-10-02T19:44:32.955192Z","steps":["trace[900187041] 'read index received' (duration: 129.568787ms)","trace[900187041] 'applied index is now lower than readState.Index' (duration: 485.609µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:32.955444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.285831ms","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-10-02T19:44:32.955598Z","caller":"traceutil/trace.go:171","msg":"trace[399788922] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26418; }","duration":"130.487345ms","start":"2026-10-02T19:44:32.825094Z","end":"2026-10-02T19:44:32.955581Z","steps":["trace[399788922] 'agreement among raft nodes before linearized reading' (duration: 130.276721ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:33.138733Z","caller":"traceutil/trace.go:171","msg":"trace[352256671] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"182.448999ms","start":"2026-10-02T19:44:32.956257Z","end":"2026-10-02T19:44:33.138706Z","steps":["trace[352256671] 'process raft request' (duration: 88.938166ms)","trace[352256671] 'compare' (duration: 93.290168ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:33.330378Z","caller":"traceutil/trace.go:171","msg":"trace[1648337723] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"370.079366ms","start":"2026-10-02T19:44:32.960272Z","end":"2026-10-02T19:44:33.330352Z","steps":["trace[1648337723] 'process raft request' (duration: 369.833601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:33.33056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:44:32.960249Z","time spent":"370.203688ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:44:33.387068Z","caller":"traceutil/trace.go:171","msg":"trace[1667404153] linearizableReadLoop","detail":"{readStateIndex:27292; appliedIndex:27289; }","duration":"246.31569ms","start":"2026-10-02T19:44:33.140655Z","end":"2026-10-02T19:44:33.38697Z","steps":["trace[1667404153] 'read index received' (duration: 189.532673ms)","trace[1667404153] 'applied index is now lower than readState.Index' (duration: 56.781797ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:33.387169Z","caller":"traceutil/trace.go:171","msg":"trace[1967501388] transaction","detail":"{read_only:false; response_revision:26422; number_of_response:1; }","duration":"272.272921ms","start":"2026-10-02T19:44:33.114851Z","end":"2026-10-02T19:44:33.387124Z","steps":["trace[1967501388] 'process raft request' (duration: 272.004847ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:33.387158Z","caller":"traceutil/trace.go:171","msg":"trace[251181551] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"273.685539ms","start":"2026-10-02T19:44:33.113418Z","end":"2026-10-02T19:44:33.387103Z","steps":["trace[251181551] 'process raft request' (duration: 273.249461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:33.387403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.726857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-10-02T19:44:33.387406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.9308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:33.387445Z","caller":"traceutil/trace.go:171","msg":"trace[189108664] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26422; }","duration":"246.822649ms","start":"2026-10-02T19:44:33.140609Z","end":"2026-10-02T19:44:33.387431Z","steps":["trace[189108664] 'agreement among raft nodes before linearized reading' (duration: 246.659556ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:33.387448Z","caller":"traceutil/trace.go:171","msg":"trace[176043458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26422; }","duration":"181.024552ms","start":"2026-10-02T19:44:33.206413Z","end":"2026-10-02T19:44:33.387437Z","steps":["trace[176043458] 'agreement among raft nodes before linearized reading' (duration: 180.9353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:33.387508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.912238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:33.38759Z","caller":"traceutil/trace.go:171","msg":"trace[729429060] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26422; }","duration":"191.99915ms","start":"2026-10-02T19:44:33.195571Z","end":"2026-10-02T19:44:33.387571Z","steps":["trace[729429060] 'agreement among raft nodes before linearized reading' (duration: 191.855907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:33.387627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.947435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9bf21e0-5ac8-4ada-8521-8f671cfc0075\" ","response":"range_response_count:1 size:3642"} {"level":"info","ts":"2026-10-02T19:44:33.387714Z","caller":"traceutil/trace.go:171","msg":"trace[1666756801] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9bf21e0-5ac8-4ada-8521-8f671cfc0075; range_end:; response_count:1; response_revision:26422; }","duration":"244.091868ms","start":"2026-10-02T19:44:33.143601Z","end":"2026-10-02T19:44:33.387693Z","steps":["trace[1666756801] 'agreement among raft nodes before linearized reading' (duration: 243.745431ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:35.561684Z","caller":"traceutil/trace.go:171","msg":"trace[1493001632] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"168.223259ms","start":"2026-10-02T19:44:35.393429Z","end":"2026-10-02T19:44:35.561652Z","steps":["trace[1493001632] 'process raft request' (duration: 83.693466ms)","trace[1493001632] 'compare' (duration: 84.317589ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:35.561897Z","caller":"traceutil/trace.go:171","msg":"trace[1809065851] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"168.28319ms","start":"2026-10-02T19:44:35.393571Z","end":"2026-10-02T19:44:35.561854Z","steps":["trace[1809065851] 'process raft request' (duration: 168.037955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:40.538807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.940827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:40.538942Z","caller":"traceutil/trace.go:171","msg":"trace[991521921] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"219.91784ms","start":"2026-10-02T19:44:40.319004Z","end":"2026-10-02T19:44:40.538922Z","steps":["trace[991521921] 'process raft request' (duration: 111.778149ms)","trace[991521921] 'compare' (duration: 107.810904ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:40.540051Z","caller":"traceutil/trace.go:171","msg":"trace[1047014696] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"220.878788ms","start":"2026-10-02T19:44:40.319142Z","end":"2026-10-02T19:44:40.540021Z","steps":["trace[1047014696] 'process raft request' (duration: 220.677115ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:40.540261Z","caller":"traceutil/trace.go:171","msg":"trace[2108435826] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"221.069731ms","start":"2026-10-02T19:44:40.319167Z","end":"2026-10-02T19:44:40.540237Z","steps":["trace[2108435826] 'process raft request' (duration: 220.790516ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:40.540611Z","caller":"traceutil/trace.go:171","msg":"trace[734501887] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"221.217654ms","start":"2026-10-02T19:44:40.319355Z","end":"2026-10-02T19:44:40.540573Z","steps":["trace[734501887] 'process raft request' (duration: 220.800656ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:40.540645Z","caller":"traceutil/trace.go:171","msg":"trace[1203360082] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"198.777448ms","start":"2026-10-02T19:44:40.341842Z","end":"2026-10-02T19:44:40.54062Z","steps":["trace[1203360082] 'process raft request' (duration: 198.574774ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:40.540766Z","caller":"traceutil/trace.go:171","msg":"trace[674810193] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"195.308803ms","start":"2026-10-02T19:44:40.34544Z","end":"2026-10-02T19:44:40.540748Z","steps":["trace[674810193] 'process raft request' (duration: 195.238802ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:40.540826Z","caller":"traceutil/trace.go:171","msg":"trace[918181697] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"198.783989ms","start":"2026-10-02T19:44:40.342032Z","end":"2026-10-02T19:44:40.540816Z","steps":["trace[918181697] 'process raft request' (duration: 198.535684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:43.954498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.201607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:44:43.954576Z","caller":"traceutil/trace.go:171","msg":"trace[1466644863] linearizableReadLoop","detail":"{readStateIndex:27406; appliedIndex:27405; }","duration":"227.87232ms","start":"2026-10-02T19:44:43.726694Z","end":"2026-10-02T19:44:43.954566Z","steps":["trace[1466644863] 'read index received' (duration: 67.49513ms)","trace[1466644863] 'applied index is now lower than readState.Index' (duration: 160.37662ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:44:43.95463Z","caller":"traceutil/trace.go:171","msg":"trace[1920845326] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"251.298053ms","start":"2026-10-02T19:44:43.703296Z","end":"2026-10-02T19:44:43.954594Z","steps":["trace[1920845326] 'process raft request' (duration: 90.955443ms)","trace[1920845326] 'compare' (duration: 159.981623ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:44:43.954683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.989143ms","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-10-02T19:44:43.954703Z","caller":"traceutil/trace.go:171","msg":"trace[1443131918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26533; }","duration":"228.034733ms","start":"2026-10-02T19:44:43.726662Z","end":"2026-10-02T19:44:43.954697Z","steps":["trace[1443131918] 'agreement among raft nodes before linearized reading' (duration: 227.957161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:44:43.954757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.527612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:44:43.954807Z","caller":"traceutil/trace.go:171","msg":"trace[1695120086] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26533; }","duration":"209.591643ms","start":"2026-10-02T19:44:43.745191Z","end":"2026-10-02T19:44:43.954783Z","steps":["trace[1695120086] 'agreement among raft nodes before linearized reading' (duration: 209.505261ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:44.157169Z","caller":"traceutil/trace.go:171","msg":"trace[790189679] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26534; }","duration":"100.011406ms","start":"2026-10-02T19:44:44.057107Z","end":"2026-10-02T19:44:44.157118Z","steps":["trace[790189679] 'range keys from in-memory index tree' (duration: 99.806212ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:46.28493Z","caller":"traceutil/trace.go:171","msg":"trace[635486063] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"146.403475ms","start":"2026-10-02T19:44:46.138508Z","end":"2026-10-02T19:44:46.284912Z","steps":["trace[635486063] 'process raft request' (duration: 143.119783ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:46.443893Z","caller":"traceutil/trace.go:171","msg":"trace[1912893884] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"110.224121ms","start":"2026-10-02T19:44:46.333633Z","end":"2026-10-02T19:44:46.443857Z","steps":["trace[1912893884] 'process raft request' (duration: 109.865724ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:44:54.674868Z","caller":"traceutil/trace.go:171","msg":"trace[858770838] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"194.552727ms","start":"2026-10-02T19:44:54.480287Z","end":"2026-10-02T19:44:54.674839Z","steps":["trace[858770838] 'process raft request' (duration: 174.961336ms)","trace[858770838] 'compare' (duration: 19.48439ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:03.918458Z","caller":"traceutil/trace.go:171","msg":"trace[1162740780] transaction","detail":"{read_only:false; response_revision:26705; number_of_response:1; }","duration":"257.843548ms","start":"2026-10-02T19:45:03.66059Z","end":"2026-10-02T19:45:03.918434Z","steps":["trace[1162740780] 'process raft request' (duration: 257.707296ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:03.918644Z","caller":"traceutil/trace.go:171","msg":"trace[829706643] linearizableReadLoop","detail":"{readStateIndex:27583; appliedIndex:27583; }","duration":"257.581954ms","start":"2026-10-02T19:45:03.661029Z","end":"2026-10-02T19:45:03.918611Z","steps":["trace[829706643] 'read index received' (duration: 257.560133ms)","trace[829706643] 'applied index is now lower than readState.Index' (duration: 19.121µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:03.918926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.874008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-10-02T19:45:03.918969Z","caller":"traceutil/trace.go:171","msg":"trace[64438885] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26705; }","duration":"257.98234ms","start":"2026-10-02T19:45:03.660975Z","end":"2026-10-02T19:45:03.918957Z","steps":["trace[64438885] 'agreement among raft nodes before linearized reading' (duration: 257.781137ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:03.934389Z","caller":"traceutil/trace.go:171","msg":"trace[276565] transaction","detail":"{read_only:false; response_revision:26706; number_of_response:1; }","duration":"265.899271ms","start":"2026-10-02T19:45:03.668458Z","end":"2026-10-02T19:45:03.934358Z","steps":["trace[276565] 'process raft request' (duration: 265.719737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:03.934462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.799727ms","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":"warn","ts":"2026-10-02T19:45:03.934462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.202959ms","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-10-02T19:45:03.934549Z","caller":"traceutil/trace.go:171","msg":"trace[948725453] 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:26706; }","duration":"267.936499ms","start":"2026-10-02T19:45:03.666579Z","end":"2026-10-02T19:45:03.934516Z","steps":["trace[948725453] 'agreement among raft nodes before linearized reading' (duration: 267.804896ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:03.934588Z","caller":"traceutil/trace.go:171","msg":"trace[64423610] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:26706; }","duration":"119.345442ms","start":"2026-10-02T19:45:03.81522Z","end":"2026-10-02T19:45:03.934565Z","steps":["trace[64423610] 'agreement among raft nodes before linearized reading' (duration: 119.139308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:03.934768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.989708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:03.934806Z","caller":"traceutil/trace.go:171","msg":"trace[2039214602] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:26706; }","duration":"193.07355ms","start":"2026-10-02T19:45:03.741721Z","end":"2026-10-02T19:45:03.934795Z","steps":["trace[2039214602] 'agreement among raft nodes before linearized reading' (duration: 193.011869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.385169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.858426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-02T19:45:04.385255Z","caller":"traceutil/trace.go:171","msg":"trace[1320769814] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26706; }","duration":"442.969328ms","start":"2026-10-02T19:45:03.942266Z","end":"2026-10-02T19:45:04.385235Z","steps":["trace[1320769814] 'range keys from in-memory index tree' (duration: 442.684363ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.385291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.942257Z","time spent":"443.025369ms","remote":"127.0.0.1:45376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-10-02T19:45:04.541213Z","caller":"traceutil/trace.go:171","msg":"trace[2117452023] linearizableReadLoop","detail":"{readStateIndex:27585; appliedIndex:27584; }","duration":"334.979199ms","start":"2026-10-02T19:45:04.206208Z","end":"2026-10-02T19:45:04.541188Z","steps":["trace[2117452023] 'read index received' (duration: 334.678864ms)","trace[2117452023] 'applied index is now lower than readState.Index' (duration: 299.385µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:04.541284Z","caller":"traceutil/trace.go:171","msg":"trace[681897519] transaction","detail":"{read_only:false; response_revision:26707; number_of_response:1; }","duration":"521.456486ms","start":"2026-10-02T19:45:04.019792Z","end":"2026-10-02T19:45:04.541248Z","steps":["trace[681897519] 'process raft request' (duration: 521.15239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.541412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.177664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:04.541452Z","caller":"traceutil/trace.go:171","msg":"trace[312738272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26707; }","duration":"335.276775ms","start":"2026-10-02T19:45:04.206163Z","end":"2026-10-02T19:45:04.54144Z","steps":["trace[312738272] 'agreement among raft nodes before linearized reading' (duration: 335.172823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.541482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.20614Z","time spent":"335.334767ms","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-02T19:45:04.541575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.433936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:04.541669Z","caller":"traceutil/trace.go:171","msg":"trace[1321835760] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26707; }","duration":"223.600399ms","start":"2026-10-02T19:45:04.318047Z","end":"2026-10-02T19:45:04.541648Z","steps":["trace[1321835760] 'agreement among raft nodes before linearized reading' (duration: 223.420096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.541663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.332456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-10-02T19:45:04.541754Z","caller":"traceutil/trace.go:171","msg":"trace[2021558341] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26707; }","duration":"144.473138ms","start":"2026-10-02T19:45:04.397258Z","end":"2026-10-02T19:45:04.541731Z","steps":["trace[2021558341] 'agreement among raft nodes before linearized reading' (duration: 144.225663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.541833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.01977Z","time spent":"521.65108ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:04.541861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.515054ms","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-10-02T19:45:04.542637Z","caller":"traceutil/trace.go:171","msg":"trace[1382457121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26707; }","duration":"189.34609ms","start":"2026-10-02T19:45:04.353254Z","end":"2026-10-02T19:45:04.5426Z","steps":["trace[1382457121] 'agreement among raft nodes before linearized reading' (duration: 188.507554ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:04.666983Z","caller":"traceutil/trace.go:171","msg":"trace[1406380793] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"119.583737ms","start":"2026-10-02T19:45:04.547373Z","end":"2026-10-02T19:45:04.666956Z","steps":["trace[1406380793] 'process raft request' (duration: 78.203602ms)","trace[1406380793] 'compare' (duration: 41.043798ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:04.667163Z","caller":"traceutil/trace.go:171","msg":"trace[549680989] linearizableReadLoop","detail":"{readStateIndex:27586; appliedIndex:27585; }","duration":"113.93655ms","start":"2026-10-02T19:45:04.553095Z","end":"2026-10-02T19:45:04.667032Z","steps":["trace[549680989] 'read index received' (duration: 72.363992ms)","trace[549680989] 'applied index is now lower than readState.Index' (duration: 41.568548ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:04.66739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.274647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-10-02T19:45:04.667438Z","caller":"traceutil/trace.go:171","msg":"trace[381200683] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26709; }","duration":"114.352658ms","start":"2026-10-02T19:45:04.553071Z","end":"2026-10-02T19:45:04.667423Z","steps":["trace[381200683] 'agreement among raft nodes before linearized reading' (duration: 114.179324ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:05.656001Z","caller":"traceutil/trace.go:171","msg":"trace[1633124676] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"100.640568ms","start":"2026-10-02T19:45:05.55533Z","end":"2026-10-02T19:45:05.65597Z","steps":["trace[1633124676] 'process raft request' (duration: 99.887993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:08.836238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.750129ms","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-10-02T19:45:08.836317Z","caller":"traceutil/trace.go:171","msg":"trace[371123895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26741; }","duration":"149.880741ms","start":"2026-10-02T19:45:08.686418Z","end":"2026-10-02T19:45:08.836299Z","steps":["trace[371123895] 'range keys from in-memory index tree' (duration: 149.508685ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:08.837216Z","caller":"traceutil/trace.go:171","msg":"trace[2115765687] linearizableReadLoop","detail":"{readStateIndex:27621; appliedIndex:27620; }","duration":"122.894319ms","start":"2026-10-02T19:45:08.714309Z","end":"2026-10-02T19:45:08.837203Z","steps":["trace[2115765687] 'read index received' (duration: 69.697821ms)","trace[2115765687] 'applied index is now lower than readState.Index' (duration: 53.195408ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:08.837432Z","caller":"traceutil/trace.go:171","msg":"trace[402318063] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"146.939537ms","start":"2026-10-02T19:45:08.690475Z","end":"2026-10-02T19:45:08.837414Z","steps":["trace[402318063] 'process raft request' (duration: 93.537184ms)","trace[402318063] 'compare' (duration: 52.873772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:08.837662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.346007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:08.837705Z","caller":"traceutil/trace.go:171","msg":"trace[311593801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26742; }","duration":"123.389098ms","start":"2026-10-02T19:45:08.714303Z","end":"2026-10-02T19:45:08.837692Z","steps":["trace[311593801] 'agreement among raft nodes before linearized reading' (duration: 123.313096ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:08.911104Z","caller":"traceutil/trace.go:171","msg":"trace[1361274296] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"157.627048ms","start":"2026-10-02T19:45:08.753452Z","end":"2026-10-02T19:45:08.911079Z","steps":["trace[1361274296] 'process raft request' (duration: 157.2191ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:08.911388Z","caller":"traceutil/trace.go:171","msg":"trace[91492093] transaction","detail":"{read_only:false; response_revision:26744; number_of_response:1; }","duration":"136.482517ms","start":"2026-10-02T19:45:08.774884Z","end":"2026-10-02T19:45:08.911367Z","steps":["trace[91492093] 'process raft request' (duration: 136.158531ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:08.911397Z","caller":"traceutil/trace.go:171","msg":"trace[1236854924] transaction","detail":"{read_only:false; response_revision:26745; number_of_response:1; }","duration":"134.648733ms","start":"2026-10-02T19:45:08.776737Z","end":"2026-10-02T19:45:08.911386Z","steps":["trace[1236854924] 'process raft request' (duration: 134.51325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:08.911591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.001599ms","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-10-02T19:45:08.911616Z","caller":"traceutil/trace.go:171","msg":"trace[943760977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26745; }","duration":"116.06749ms","start":"2026-10-02T19:45:08.795541Z","end":"2026-10-02T19:45:08.911609Z","steps":["trace[943760977] 'agreement among raft nodes before linearized reading' (duration: 115.955008ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:09.066686Z","caller":"traceutil/trace.go:171","msg":"trace[1258060623] linearizableReadLoop","detail":"{readStateIndex:27625; appliedIndex:27624; }","duration":"155.319965ms","start":"2026-10-02T19:45:08.911336Z","end":"2026-10-02T19:45:09.066656Z","steps":["trace[1258060623] 'read index received' (duration: 154.657352ms)","trace[1258060623] 'applied index is now lower than readState.Index' (duration: 661.362µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:09.066798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.759644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:45:09.066821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.594001ms","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-10-02T19:45:09.066861Z","caller":"traceutil/trace.go:171","msg":"trace[195192913] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26746; }","duration":"174.867055ms","start":"2026-10-02T19:45:08.891977Z","end":"2026-10-02T19:45:09.066844Z","steps":["trace[195192913] 'agreement among raft nodes before linearized reading' (duration: 174.708762ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:09.066835Z","caller":"traceutil/trace.go:171","msg":"trace[41083306] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"224.17752ms","start":"2026-10-02T19:45:08.84263Z","end":"2026-10-02T19:45:09.066807Z","steps":["trace[41083306] 'process raft request' (duration: 223.282873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:09.066895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.365623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-02T19:45:09.066907Z","caller":"traceutil/trace.go:171","msg":"trace[2093123929] 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:26746; }","duration":"203.704553ms","start":"2026-10-02T19:45:08.863182Z","end":"2026-10-02T19:45:09.066887Z","steps":["trace[2093123929] 'agreement among raft nodes before linearized reading' (duration: 203.53003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:09.066911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.020104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-02T19:45:09.066932Z","caller":"traceutil/trace.go:171","msg":"trace[826439470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26746; }","duration":"216.445584ms","start":"2026-10-02T19:45:08.850475Z","end":"2026-10-02T19:45:09.06692Z","steps":["trace[826439470] 'agreement among raft nodes before linearized reading' (duration: 216.288251ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:09.066973Z","caller":"traceutil/trace.go:171","msg":"trace[424967160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26746; }","duration":"179.138986ms","start":"2026-10-02T19:45:08.887819Z","end":"2026-10-02T19:45:09.066958Z","steps":["trace[424967160] 'agreement among raft nodes before linearized reading' (duration: 178.903882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:09.067024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.922117ms","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-10-02T19:45:09.067082Z","caller":"traceutil/trace.go:171","msg":"trace[310601051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26746; }","duration":"204.016448ms","start":"2026-10-02T19:45:08.863048Z","end":"2026-10-02T19:45:09.067064Z","steps":["trace[310601051] 'agreement among raft nodes before linearized reading' (duration: 203.881216ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:09.079898Z","caller":"traceutil/trace.go:171","msg":"trace[1534124844] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"165.720352ms","start":"2026-10-02T19:45:08.914157Z","end":"2026-10-02T19:45:09.079877Z","steps":["trace[1534124844] 'process raft request' (duration: 165.62055ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:09.800871Z","caller":"traceutil/trace.go:171","msg":"trace[715975846] linearizableReadLoop","detail":"{readStateIndex:27631; appliedIndex:27630; }","duration":"104.887538ms","start":"2026-10-02T19:45:09.695954Z","end":"2026-10-02T19:45:09.800842Z","steps":["trace[715975846] 'read index received' (duration: 104.690495ms)","trace[715975846] 'applied index is now lower than readState.Index' (duration: 195.133µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:09.801027Z","caller":"traceutil/trace.go:171","msg":"trace[268091000] transaction","detail":"{read_only:false; response_revision:26752; number_of_response:1; }","duration":"126.800773ms","start":"2026-10-02T19:45:09.67416Z","end":"2026-10-02T19:45:09.800961Z","steps":["trace[268091000] 'process raft request' (duration: 126.491228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:09.801235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.237675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-10-02T19:45:09.801376Z","caller":"traceutil/trace.go:171","msg":"trace[826328705] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26752; }","duration":"105.456439ms","start":"2026-10-02T19:45:09.695891Z","end":"2026-10-02T19:45:09.801347Z","steps":["trace[826328705] 'agreement among raft nodes before linearized reading' (duration: 105.089302ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:09.814042Z","caller":"traceutil/trace.go:171","msg":"trace[1282663332] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"100.850592ms","start":"2026-10-02T19:45:09.713172Z","end":"2026-10-02T19:45:09.814022Z","steps":["trace[1282663332] 'process raft request' (duration: 100.273871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:11.267888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.87888ms","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-10-02T19:45:11.267972Z","caller":"traceutil/trace.go:171","msg":"trace[1130010729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26763; }","duration":"150.993623ms","start":"2026-10-02T19:45:11.116959Z","end":"2026-10-02T19:45:11.267953Z","steps":["trace[1130010729] 'range keys from in-memory index tree' (duration: 150.714397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:11.268053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.279733ms","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-10-02T19:45:11.268603Z","caller":"traceutil/trace.go:171","msg":"trace[1023336031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26763; }","duration":"194.935305ms","start":"2026-10-02T19:45:11.073629Z","end":"2026-10-02T19:45:11.268564Z","steps":["trace[1023336031] 'range keys from in-memory index tree' (duration: 193.969816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:11.268062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.62656ms","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-10-02T19:45:11.268746Z","caller":"traceutil/trace.go:171","msg":"trace[1363437538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26763; }","duration":"185.339433ms","start":"2026-10-02T19:45:11.083369Z","end":"2026-10-02T19:45:11.268709Z","steps":["trace[1363437538] 'range keys from in-memory index tree' (duration: 184.257383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:11.26816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.189886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-02T19:45:11.268844Z","caller":"traceutil/trace.go:171","msg":"trace[22661500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26763; }","duration":"151.89958ms","start":"2026-10-02T19:45:11.11692Z","end":"2026-10-02T19:45:11.26882Z","steps":["trace[22661500] 'range keys from in-memory index tree' (duration: 150.710357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:11.268243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.30081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-02T19:45:11.268971Z","caller":"traceutil/trace.go:171","msg":"trace[570188000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26763; }","duration":"160.018653ms","start":"2026-10-02T19:45:11.108899Z","end":"2026-10-02T19:45:11.268917Z","steps":["trace[570188000] 'range keys from in-memory index tree' (duration: 158.736189ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:11.475545Z","caller":"traceutil/trace.go:171","msg":"trace[1694683875] transaction","detail":"{read_only:false; response_revision:26764; number_of_response:1; }","duration":"202.60686ms","start":"2026-10-02T19:45:11.272886Z","end":"2026-10-02T19:45:11.475492Z","steps":["trace[1694683875] 'process raft request' (duration: 202.371936ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:11.525629Z","caller":"traceutil/trace.go:171","msg":"trace[624286617] transaction","detail":"{read_only:false; response_revision:26765; number_of_response:1; }","duration":"252.339914ms","start":"2026-10-02T19:45:11.273267Z","end":"2026-10-02T19:45:11.525607Z","steps":["trace[624286617] 'process raft request' (duration: 252.10826ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:11.526036Z","caller":"traceutil/trace.go:171","msg":"trace[71777267] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"252.12097ms","start":"2026-10-02T19:45:11.273888Z","end":"2026-10-02T19:45:11.526009Z","steps":["trace[71777267] 'process raft request' (duration: 251.655611ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:11.526317Z","caller":"traceutil/trace.go:171","msg":"trace[770857701] transaction","detail":"{read_only:false; response_revision:26767; number_of_response:1; }","duration":"252.11594ms","start":"2026-10-02T19:45:11.274182Z","end":"2026-10-02T19:45:11.526298Z","steps":["trace[770857701] 'process raft request' (duration: 251.712812ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:11.526549Z","caller":"traceutil/trace.go:171","msg":"trace[97544765] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"250.194134ms","start":"2026-10-02T19:45:11.276318Z","end":"2026-10-02T19:45:11.526512Z","steps":["trace[97544765] 'process raft request' (duration: 249.907858ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:27.349966Z","caller":"traceutil/trace.go:171","msg":"trace[667997952] transaction","detail":"{read_only:false; response_revision:26887; number_of_response:1; }","duration":"368.414915ms","start":"2026-10-02T19:45:26.981506Z","end":"2026-10-02T19:45:27.349921Z","steps":["trace[667997952] 'process raft request' (duration: 349.879583ms)","trace[667997952] 'compare' (duration: 18.40639ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:27.350139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.981454Z","time spent":"368.591708ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:27.791863Z","caller":"traceutil/trace.go:171","msg":"trace[1187744329] transaction","detail":"{read_only:false; response_revision:26888; number_of_response:1; }","duration":"809.121069ms","start":"2026-10-02T19:45:26.982716Z","end":"2026-10-02T19:45:27.791837Z","steps":["trace[1187744329] 'process raft request' (duration: 743.000176ms)","trace[1187744329] 'compare' (duration: 65.939849ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:27.792011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.982687Z","time spent":"809.244361ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:27.792148Z","caller":"traceutil/trace.go:171","msg":"trace[1369098901] transaction","detail":"{read_only:false; response_revision:26890; number_of_response:1; }","duration":"665.249431ms","start":"2026-10-02T19:45:27.126875Z","end":"2026-10-02T19:45:27.792124Z","steps":["trace[1369098901] 'process raft request' (duration: 665.120809ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:27.792169Z","caller":"traceutil/trace.go:171","msg":"trace[1752814004] transaction","detail":"{read_only:false; response_revision:26889; number_of_response:1; }","duration":"809.017146ms","start":"2026-10-02T19:45:26.983122Z","end":"2026-10-02T19:45:27.792139Z","steps":["trace[1752814004] 'process raft request' (duration: 808.67191ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:27.79223Z","caller":"traceutil/trace.go:171","msg":"trace[374973250] transaction","detail":"{read_only:false; response_revision:26891; number_of_response:1; }","duration":"611.720628ms","start":"2026-10-02T19:45:27.180478Z","end":"2026-10-02T19:45:27.792199Z","steps":["trace[374973250] 'process raft request' (duration: 611.603705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.792278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.126837Z","time spent":"665.367014ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:27.792321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.9831Z","time spent":"809.144679ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:27.792377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.180455Z","time spent":"611.841499ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:27.792431Z","caller":"traceutil/trace.go:171","msg":"trace[1177876948] linearizableReadLoop","detail":"{readStateIndex:27771; appliedIndex:27768; }","duration":"757.230696ms","start":"2026-10-02T19:45:27.035185Z","end":"2026-10-02T19:45:27.792416Z","steps":["trace[1177876948] 'read index received' (duration: 296.375838ms)","trace[1177876948] 'applied index is now lower than readState.Index' (duration: 460.853678ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:27.792463Z","caller":"traceutil/trace.go:171","msg":"trace[1187850215] transaction","detail":"{read_only:false; response_revision:26892; number_of_response:1; }","duration":"335.580181ms","start":"2026-10-02T19:45:27.456664Z","end":"2026-10-02T19:45:27.792245Z","steps":["trace[1187850215] 'process raft request' (duration: 335.50995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.792583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.294266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:27.792628Z","caller":"traceutil/trace.go:171","msg":"trace[761857210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26892; }","duration":"585.379648ms","start":"2026-10-02T19:45:27.207237Z","end":"2026-10-02T19:45:27.792617Z","steps":["trace[761857210] 'agreement among raft nodes before linearized reading' (duration: 585.302146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.792643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.890846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-02T19:45:27.792661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.207218Z","time spent":"585.437388ms","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-02T19:45:27.792686Z","caller":"traceutil/trace.go:171","msg":"trace[680096422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26892; }","duration":"155.966598ms","start":"2026-10-02T19:45:27.636707Z","end":"2026-10-02T19:45:27.792674Z","steps":["trace[680096422] 'agreement among raft nodes before linearized reading' (duration: 155.838975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.792715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.473281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-02T19:45:27.792761Z","caller":"traceutil/trace.go:171","msg":"trace[251509522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26892; }","duration":"152.555292ms","start":"2026-10-02T19:45:27.640192Z","end":"2026-10-02T19:45:27.792748Z","steps":["trace[251509522] 'agreement among raft nodes before linearized reading' (duration: 152.411739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.792723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.49235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:45:27.792857Z","caller":"traceutil/trace.go:171","msg":"trace[694085556] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:26892; }","duration":"757.713025ms","start":"2026-10-02T19:45:27.035119Z","end":"2026-10-02T19:45:27.792832Z","steps":["trace[694085556] 'agreement among raft nodes before linearized reading' (duration: 757.384698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.792861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.269291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-02T19:45:27.792851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.758381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-02T19:45:27.792878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.978604ms","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-10-02T19:45:27.792916Z","caller":"traceutil/trace.go:171","msg":"trace[1744268843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26892; }","duration":"243.367543ms","start":"2026-10-02T19:45:27.549536Z","end":"2026-10-02T19:45:27.792904Z","steps":["trace[1744268843] 'agreement among raft nodes before linearized reading' (duration: 243.247741ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:27.792934Z","caller":"traceutil/trace.go:171","msg":"trace[541982875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26892; }","duration":"159.057887ms","start":"2026-10-02T19:45:27.633865Z","end":"2026-10-02T19:45:27.792923Z","steps":["trace[541982875] 'agreement among raft nodes before linearized reading' (duration: 158.898633ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:27.792933Z","caller":"traceutil/trace.go:171","msg":"trace[1846792648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26892; }","duration":"186.890073ms","start":"2026-10-02T19:45:27.606024Z","end":"2026-10-02T19:45:27.792914Z","steps":["trace[1846792648] 'agreement among raft nodes before linearized reading' (duration: 186.629568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.792971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.456637Z","time spent":"335.924039ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:27.793067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.035091Z","time spent":"757.95602ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":18,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-10-02T19:45:33.114766Z","caller":"traceutil/trace.go:171","msg":"trace[1244387647] linearizableReadLoop","detail":"{readStateIndex:27826; appliedIndex:27825; }","duration":"119.403725ms","start":"2026-10-02T19:45:32.995338Z","end":"2026-10-02T19:45:33.114742Z","steps":["trace[1244387647] 'read index received' (duration: 119.311483ms)","trace[1244387647] 'applied index is now lower than readState.Index' (duration: 91.152µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:33.114837Z","caller":"traceutil/trace.go:171","msg":"trace[1967985278] transaction","detail":"{read_only:false; response_revision:26942; number_of_response:1; }","duration":"162.539111ms","start":"2026-10-02T19:45:32.952275Z","end":"2026-10-02T19:45:33.114814Z","steps":["trace[1967985278] 'process raft request' (duration: 162.317917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:33.115075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.725951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19229"} {"level":"info","ts":"2026-10-02T19:45:33.115397Z","caller":"traceutil/trace.go:171","msg":"trace[696004588] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26942; }","duration":"120.097757ms","start":"2026-10-02T19:45:32.995283Z","end":"2026-10-02T19:45:33.11538Z","steps":["trace[696004588] 'agreement among raft nodes before linearized reading' (duration: 119.559578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:33.370854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.831707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:33.370962Z","caller":"traceutil/trace.go:171","msg":"trace[354790111] linearizableReadLoop","detail":"{readStateIndex:27827; appliedIndex:27826; }","duration":"256.114685ms","start":"2026-10-02T19:45:33.114829Z","end":"2026-10-02T19:45:33.370943Z","steps":["trace[354790111] 'read index received' (duration: 102.045224ms)","trace[354790111] 'applied index is now lower than readState.Index' (duration: 154.067951ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:33.37103Z","caller":"traceutil/trace.go:171","msg":"trace[917803311] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"354.809056ms","start":"2026-10-02T19:45:33.016174Z","end":"2026-10-02T19:45:33.370983Z","steps":["trace[917803311] 'process raft request' (duration: 200.758496ms)","trace[917803311] 'compare' (duration: 153.705123ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:33.371247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:33.016149Z","time spent":"354.974139ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:33.371239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.146432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1137"} {"level":"warn","ts":"2026-10-02T19:45:33.371087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.355865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:33.371324Z","caller":"traceutil/trace.go:171","msg":"trace[1700756205] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:26943; }","duration":"250.268275ms","start":"2026-10-02T19:45:33.12103Z","end":"2026-10-02T19:45:33.371298Z","steps":["trace[1700756205] 'agreement among raft nodes before linearized reading' (duration: 250.042171ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:33.371367Z","caller":"traceutil/trace.go:171","msg":"trace[397177510] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26943; }","duration":"299.660811ms","start":"2026-10-02T19:45:33.071671Z","end":"2026-10-02T19:45:33.371332Z","steps":["trace[397177510] 'agreement among raft nodes before linearized reading' (duration: 299.369745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:33.371426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.924236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:33.371582Z","caller":"traceutil/trace.go:171","msg":"trace[1715327200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26943; }","duration":"165.12387ms","start":"2026-10-02T19:45:33.206429Z","end":"2026-10-02T19:45:33.371553Z","steps":["trace[1715327200] 'agreement among raft nodes before linearized reading' (duration: 164.874095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:33.887186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1859319233777062330,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-02T19:45:33.892913Z","caller":"traceutil/trace.go:171","msg":"trace[267130031] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"506.749566ms","start":"2026-10-02T19:45:33.386136Z","end":"2026-10-02T19:45:33.892885Z","steps":["trace[267130031] 'process raft request' (duration: 506.551032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:33.893163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:33.386118Z","time spent":"506.853539ms","remote":"127.0.0.1:45492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:33.925434Z","caller":"traceutil/trace.go:171","msg":"trace[186473316] transaction","detail":"{read_only:false; response_revision:26945; number_of_response:1; }","duration":"114.399928ms","start":"2026-10-02T19:45:33.811005Z","end":"2026-10-02T19:45:33.925405Z","steps":["trace[186473316] 'process raft request' (duration: 114.205254ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:33.925575Z","caller":"traceutil/trace.go:171","msg":"trace[2090113107] linearizableReadLoop","detail":"{readStateIndex:27830; appliedIndex:27827; }","duration":"539.337374ms","start":"2026-10-02T19:45:33.386209Z","end":"2026-10-02T19:45:33.925546Z","steps":["trace[2090113107] 'read index received' (duration: 506.510362ms)","trace[2090113107] 'applied index is now lower than readState.Index' (duration: 32.824602ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:33.925802Z","caller":"traceutil/trace.go:171","msg":"trace[842074664] transaction","detail":"{read_only:false; response_revision:26946; number_of_response:1; }","duration":"113.256207ms","start":"2026-10-02T19:45:33.812505Z","end":"2026-10-02T19:45:33.925762Z","steps":["trace[842074664] 'process raft request' (duration: 112.854659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:33.925844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.603169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:683"} {"level":"warn","ts":"2026-10-02T19:45:33.925862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.09799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:45:33.926113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.718183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:45:33.926176Z","caller":"traceutil/trace.go:171","msg":"trace[537039227] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:26946; }","duration":"240.826305ms","start":"2026-10-02T19:45:33.685332Z","end":"2026-10-02T19:45:33.926158Z","steps":["trace[537039227] 'agreement among raft nodes before linearized reading' (duration: 240.716033ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:33.926146Z","caller":"traceutil/trace.go:171","msg":"trace[1439912129] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26946; }","duration":"366.435696ms","start":"2026-10-02T19:45:33.559687Z","end":"2026-10-02T19:45:33.926123Z","steps":["trace[1439912129] 'agreement among raft nodes before linearized reading' (duration: 366.070329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:33.92604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.528435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-02T19:45:33.92644Z","caller":"traceutil/trace.go:171","msg":"trace[1603217380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26946; }","duration":"100.941503ms","start":"2026-10-02T19:45:33.825449Z","end":"2026-10-02T19:45:33.926391Z","steps":["trace[1603217380] 'agreement among raft nodes before linearized reading' (duration: 100.507665ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:33.926045Z","caller":"traceutil/trace.go:171","msg":"trace[720100132] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:26946; }","duration":"539.816074ms","start":"2026-10-02T19:45:33.386176Z","end":"2026-10-02T19:45:33.925992Z","steps":["trace[720100132] 'agreement among raft nodes before linearized reading' (duration: 539.497857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:33.926563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:33.386165Z","time spent":"540.379264ms","remote":"127.0.0.1:45382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":706,"request content":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" "} {"level":"warn","ts":"2026-10-02T19:45:33.926473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:33.559661Z","time spent":"366.695662ms","remote":"127.0.0.1:59650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-10-02T19:45:34.140825Z","caller":"traceutil/trace.go:171","msg":"trace[340567907] transaction","detail":"{read_only:false; response_revision:26948; number_of_response:1; }","duration":"210.099973ms","start":"2026-10-02T19:45:33.930674Z","end":"2026-10-02T19:45:34.140774Z","steps":["trace[340567907] 'process raft request' (duration: 209.680435ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:34.14096Z","caller":"traceutil/trace.go:171","msg":"trace[1474298917] transaction","detail":"{read_only:false; response_revision:26950; number_of_response:1; }","duration":"209.89672ms","start":"2026-10-02T19:45:33.931054Z","end":"2026-10-02T19:45:34.140951Z","steps":["trace[1474298917] 'process raft request' (duration: 209.638415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:34.140977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.88769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:34.141026Z","caller":"traceutil/trace.go:171","msg":"trace[185232953] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26952; }","duration":"208.988212ms","start":"2026-10-02T19:45:33.932025Z","end":"2026-10-02T19:45:34.141013Z","steps":["trace[185232953] 'agreement among raft nodes before linearized reading' (duration: 208.86742ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:34.140842Z","caller":"traceutil/trace.go:171","msg":"trace[1053108558] linearizableReadLoop","detail":"{readStateIndex:27834; appliedIndex:27830; }","duration":"208.758107ms","start":"2026-10-02T19:45:33.932061Z","end":"2026-10-02T19:45:34.140819Z","steps":["trace[1053108558] 'read index received' (duration: 59.447826ms)","trace[1053108558] 'applied index is now lower than readState.Index' (duration: 149.308911ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:34.141114Z","caller":"traceutil/trace.go:171","msg":"trace[1575573312] transaction","detail":"{read_only:false; response_revision:26952; number_of_response:1; }","duration":"208.286689ms","start":"2026-10-02T19:45:33.932799Z","end":"2026-10-02T19:45:34.141085Z","steps":["trace[1575573312] 'process raft request' (duration: 207.979823ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:34.140825Z","caller":"traceutil/trace.go:171","msg":"trace[743412966] transaction","detail":"{read_only:false; response_revision:26949; number_of_response:1; }","duration":"209.8897ms","start":"2026-10-02T19:45:33.9309Z","end":"2026-10-02T19:45:34.14079Z","steps":["trace[743412966] 'process raft request' (duration: 209.708686ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:34.140917Z","caller":"traceutil/trace.go:171","msg":"trace[465003577] transaction","detail":"{read_only:false; response_revision:26951; number_of_response:1; }","duration":"208.733017ms","start":"2026-10-02T19:45:33.93217Z","end":"2026-10-02T19:45:34.140903Z","steps":["trace[465003577] 'process raft request' (duration: 208.570014ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:34.193885Z","caller":"traceutil/trace.go:171","msg":"trace[1692176127] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"138.565296ms","start":"2026-10-02T19:45:34.055295Z","end":"2026-10-02T19:45:34.193861Z","steps":["trace[1692176127] 'process raft request' (duration: 138.369023ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:34.194187Z","caller":"traceutil/trace.go:171","msg":"trace[1240786865] transaction","detail":"{read_only:false; response_revision:26954; number_of_response:1; }","duration":"122.729246ms","start":"2026-10-02T19:45:34.071434Z","end":"2026-10-02T19:45:34.194164Z","steps":["trace[1240786865] 'process raft request' (duration: 122.371609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:35.064235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.349807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:35.064442Z","caller":"traceutil/trace.go:171","msg":"trace[979599084] transaction","detail":"{read_only:false; response_revision:26956; number_of_response:1; }","duration":"215.398853ms","start":"2026-10-02T19:45:34.849023Z","end":"2026-10-02T19:45:35.064422Z","steps":["trace[979599084] 'process raft request' (duration: 90.773581ms)","trace[979599084] 'compare' (duration: 124.193584ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:35.77657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.097488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:35.776712Z","caller":"traceutil/trace.go:171","msg":"trace[1731398971] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26957; }","duration":"119.332653ms","start":"2026-10-02T19:45:35.657349Z","end":"2026-10-02T19:45:35.776682Z","steps":["trace[1731398971] 'count revisions from in-memory index tree' (duration: 118.933645ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:35.776596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.928696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:35.776922Z","caller":"traceutil/trace.go:171","msg":"trace[1225375443] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26957; }","duration":"194.297864ms","start":"2026-10-02T19:45:35.582581Z","end":"2026-10-02T19:45:35.776879Z","steps":["trace[1225375443] 'range keys from in-memory index tree' (duration: 193.824325ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:49.371605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24915} {"level":"info","ts":"2026-10-02T19:46:49.528594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24915,"took":"152.941479ms","hash":1912206975,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":35762176,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-02T19:46:49.528654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1912206975,"revision":24915,"compact-revision":21660} {"level":"info","ts":"2026-10-02T19:49:14.257024Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e4145bf1b1a19cd","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T19:49:14.262132Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-02T19:49:14.26222Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-02T19:51:49.38001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27682} {"level":"info","ts":"2026-10-02T19:51:49.51389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27682,"took":"129.676439ms","hash":1176162780,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":36716544,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-02T19:51:49.513962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1176162780,"revision":27682,"compact-revision":24915} {"level":"info","ts":"2026-10-02T19:56:49.387978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30372} {"level":"info","ts":"2026-10-02T19:56:49.533549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30372,"took":"141.35234ms","hash":3026891709,"current-db-size-bytes":57163776,"current-db-size":"57 MB","current-db-size-in-use-bytes":30638080,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T19:56:49.533619Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3026891709,"revision":30372,"compact-revision":27682} {"level":"info","ts":"2026-10-02T20:01:49.423341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32626} {"level":"info","ts":"2026-10-02T20:01:49.586546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32626,"took":"158.555195ms","hash":1374550394,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":30683136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T20:01:49.586611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1374550394,"revision":32626,"compact-revision":30372} {"level":"info","ts":"2026-10-02T20:06:49.432735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34919} {"level":"info","ts":"2026-10-02T20:06:49.581059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34919,"took":"144.344896ms","hash":1444779685,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T20:06:49.58116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1444779685,"revision":34919,"compact-revision":32626} {"level":"info","ts":"2026-10-02T20:10:11.455148Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e4145bf1b1a19cd","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T20:10:11.462408Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-02T20:10:11.462506Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-02T20:11:49.440896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37249} {"level":"info","ts":"2026-10-02T20:11:49.576494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37249,"took":"131.256768ms","hash":1655824346,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":33247232,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T20:11:49.576577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1655824346,"revision":37249,"compact-revision":34919} {"level":"info","ts":"2026-10-02T20:16:49.458113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39523} {"level":"info","ts":"2026-10-02T20:16:49.60134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39523,"took":"132.793688ms","hash":523761794,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":30408704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-02T20:16:49.601411Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":523761794,"revision":39523,"compact-revision":37249} {"level":"info","ts":"2026-10-02T20:21:49.466129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41752} {"level":"info","ts":"2026-10-02T20:21:49.593851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41752,"took":"123.804377ms","hash":1045796479,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":29024256,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T20:21:49.593924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1045796479,"revision":41752,"compact-revision":39523} {"level":"info","ts":"2026-10-02T20:26:49.473595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43972} {"level":"info","ts":"2026-10-02T20:26:49.60732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43972,"took":"129.803961ms","hash":4221929938,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":28823552,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T20:26:49.607414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4221929938,"revision":43972,"compact-revision":41752}