{"level":"warn","ts":"2026-10-20T16:40:04.072952Z","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-20T16:40:04.073041Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.239: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.239:2380","--initial-cluster=instance=https://162.253.55.239:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.239:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.239: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-20T16:40:04.073119Z","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-20T16:40:04.073133Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.239:2380"]} {"level":"info","ts":"2026-10-20T16:40:04.073157Z","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-20T16:40:04.073913Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"]} {"level":"info","ts":"2026-10-20T16:40:04.074017Z","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.239:2380"],"listen-peer-urls":["https://162.253.55.239:2380"],"advertise-client-urls":["https://162.253.55.239:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.239: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-20T16:40:04.077523Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.265372ms"} {"level":"info","ts":"2026-10-20T16:40:04.084543Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"548b318b3528a14c","cluster-id":"8bf5e58b9331730a"} {"level":"info","ts":"2026-10-20T16:40:04.084603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=()"} {"level":"info","ts":"2026-10-20T16:40:04.084624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became follower at term 0"} {"level":"info","ts":"2026-10-20T16:40:04.084639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 548b318b3528a14c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-20T16:40:04.084654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became follower at term 1"} {"level":"info","ts":"2026-10-20T16:40:04.084688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=(6092017394910798156)"} {"level":"warn","ts":"2026-10-20T16:40:04.091542Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-20T16:40:04.093395Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-20T16:40:04.095233Z","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-20T16:40:04.098503Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"548b318b3528a14c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-20T16:40:04.098681Z","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-20T16:40:04.098791Z","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-20T16:40:04.098813Z","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-20T16:40:04.098793Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"548b318b3528a14c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-20T16:40:04.101236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=(6092017394910798156)"} {"level":"info","ts":"2026-10-20T16:40:04.101911Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8bf5e58b9331730a","local-member-id":"548b318b3528a14c","added-peer-id":"548b318b3528a14c","added-peer-peer-urls":["https://162.253.55.239:2380"]} {"level":"info","ts":"2026-10-20T16:40:04.102904Z","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-20T16:40:04.103204Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"548b318b3528a14c","initial-advertise-peer-urls":["https://162.253.55.239:2380"],"listen-peer-urls":["https://162.253.55.239:2380"],"advertise-client-urls":["https://162.253.55.239:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-20T16:40:04.103171Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.239:2380"} {"level":"info","ts":"2026-10-20T16:40:04.103332Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.239:2380"} {"level":"info","ts":"2026-10-20T16:40:04.103229Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-20T16:40:04.884898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c is starting a new election at term 1"} {"level":"info","ts":"2026-10-20T16:40:04.884987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-20T16:40:04.885036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c received MsgPreVoteResp from 548b318b3528a14c at term 1"} {"level":"info","ts":"2026-10-20T16:40:04.885069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became candidate at term 2"} {"level":"info","ts":"2026-10-20T16:40:04.885086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c received MsgVoteResp from 548b318b3528a14c at term 2"} {"level":"info","ts":"2026-10-20T16:40:04.885101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became leader at term 2"} {"level":"info","ts":"2026-10-20T16:40:04.885121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 548b318b3528a14c elected leader 548b318b3528a14c at term 2"} {"level":"info","ts":"2026-10-20T16:40:04.8869Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"548b318b3528a14c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.239:2379]}","request-path":"/0/members/548b318b3528a14c/attributes","cluster-id":"8bf5e58b9331730a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-20T16:40:04.886924Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-20T16:40:04.88693Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-20T16:40:04.887066Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-20T16:40:04.88724Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-20T16:40:04.887309Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-20T16:40:04.888259Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8bf5e58b9331730a","local-member-id":"548b318b3528a14c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-20T16:40:04.888377Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-20T16:40:04.888423Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-20T16:40:04.890569Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.239:2379"} {"level":"info","ts":"2026-10-20T16:40:04.890866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-20T16:40:20.721678Z","caller":"traceutil/trace.go:171","msg":"trace[1321871447] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"145.11487ms","start":"2026-10-20T16:40:20.576524Z","end":"2026-10-20T16:40:20.721638Z","steps":["trace[1321871447] 'process raft request' (duration: 144.893335ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:40:56.309477Z","caller":"traceutil/trace.go:171","msg":"trace[1462347420] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"143.95387ms","start":"2026-10-20T16:40:56.165498Z","end":"2026-10-20T16:40:56.309452Z","steps":["trace[1462347420] 'read index received' (duration: 143.847698ms)","trace[1462347420] 'applied index is now lower than readState.Index' (duration: 104.782µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:40:56.309609Z","caller":"traceutil/trace.go:171","msg":"trace[1848823642] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"203.093663ms","start":"2026-10-20T16:40:56.106476Z","end":"2026-10-20T16:40:56.30957Z","steps":["trace[1848823642] 'process raft request' (duration: 202.848547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:40:56.309922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.367159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t5clj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-20T16:40:56.310063Z","caller":"traceutil/trace.go:171","msg":"trace[2143755988] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t5clj; range_end:; response_count:1; response_revision:603; }","duration":"144.596424ms","start":"2026-10-20T16:40:56.16544Z","end":"2026-10-20T16:40:56.310036Z","steps":["trace[2143755988] 'agreement among raft nodes before linearized reading' (duration: 144.171875ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:40:56.503284Z","caller":"traceutil/trace.go:171","msg":"trace[2064748773] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"142.819634ms","start":"2026-10-20T16:40:56.360417Z","end":"2026-10-20T16:40:56.503237Z","steps":["trace[2064748773] 'read index received' (duration: 135.864861ms)","trace[2064748773] 'applied index is now lower than readState.Index' (duration: 6.953563ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:40:56.503375Z","caller":"traceutil/trace.go:171","msg":"trace[2031280120] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"173.615622ms","start":"2026-10-20T16:40:56.329718Z","end":"2026-10-20T16:40:56.503334Z","steps":["trace[2031280120] 'process raft request' (duration: 166.69242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:40:56.503443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.992368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T16:40:56.503483Z","caller":"traceutil/trace.go:171","msg":"trace[507261136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"143.060759ms","start":"2026-10-20T16:40:56.360409Z","end":"2026-10-20T16:40:56.50347Z","steps":["trace[507261136] 'agreement among raft nodes before linearized reading' (duration: 142.963347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:40:57.033522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.118434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-20T16:40:57.033627Z","caller":"traceutil/trace.go:171","msg":"trace[1053302609] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"436.099034ms","start":"2026-10-20T16:40:56.597508Z","end":"2026-10-20T16:40:57.033607Z","steps":["trace[1053302609] 'process raft request' (duration: 301.299446ms)","trace[1053302609] 'compare' (duration: 133.98799ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:40:57.033688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:40:56.597483Z","time spent":"436.172115ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T16:40:57.1497Z","caller":"traceutil/trace.go:171","msg":"trace[1632723132] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"242.811618ms","start":"2026-10-20T16:40:56.906827Z","end":"2026-10-20T16:40:57.149638Z","steps":["trace[1632723132] 'read index received' (duration: 223.690597ms)","trace[1632723132] 'applied index is now lower than readState.Index' (duration: 19.120071ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:40:57.149974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.148242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-20T16:40:57.150023Z","caller":"traceutil/trace.go:171","msg":"trace[1000285319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"212.234054ms","start":"2026-10-20T16:40:56.937772Z","end":"2026-10-20T16:40:57.150006Z","steps":["trace[1000285319] 'agreement among raft nodes before linearized reading' (duration: 212.04912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:40:57.15004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.182926ms","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-20T16:40:57.149983Z","caller":"traceutil/trace.go:171","msg":"trace[692742914] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"389.961258ms","start":"2026-10-20T16:40:56.75982Z","end":"2026-10-20T16:40:57.149782Z","steps":["trace[692742914] 'process raft request' (duration: 371.482351ms)","trace[692742914] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; req_size:1036249; } (duration: 14.07572ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:40:57.150118Z","caller":"traceutil/trace.go:171","msg":"trace[510455351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"243.307169ms","start":"2026-10-20T16:40:56.906793Z","end":"2026-10-20T16:40:57.1501Z","steps":["trace[510455351] 'agreement among raft nodes before linearized reading' (duration: 243.027803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:40:57.150369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:40:56.759792Z","time spent":"390.411608ms","remote":"127.0.0.1:35922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036253,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T16:41:11.800911Z","caller":"traceutil/trace.go:171","msg":"trace[193948113] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"358.606427ms","start":"2026-10-20T16:41:11.442282Z","end":"2026-10-20T16:41:11.800888Z","steps":["trace[193948113] 'process raft request' (duration: 358.441324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:41:11.801051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:41:11.442261Z","time spent":"358.71982ms","remote":"127.0.0.1:36020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4895,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-20T16:42:27.783307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.180661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-20T16:42:27.783572Z","caller":"traceutil/trace.go:171","msg":"trace[124895591] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"132.30524ms","start":"2026-10-20T16:42:27.651245Z","end":"2026-10-20T16:42:27.78355Z","steps":["trace[124895591] 'process raft request' (duration: 132.195847ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:27.78375Z","caller":"traceutil/trace.go:171","msg":"trace[1011210843] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"135.847068ms","start":"2026-10-20T16:42:27.647872Z","end":"2026-10-20T16:42:27.783719Z","steps":["trace[1011210843] 'read index received' (duration: 11.119075ms)","trace[1011210843] 'applied index is now lower than readState.Index' (duration: 124.725213ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:42:27.784003Z","caller":"traceutil/trace.go:171","msg":"trace[1003337973] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"136.788118ms","start":"2026-10-20T16:42:27.647158Z","end":"2026-10-20T16:42:27.783946Z","steps":["trace[1003337973] 'process raft request' (duration: 11.810419ms)","trace[1003337973] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 123.869284ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:42:27.784096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.201595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-20T16:42:27.784135Z","caller":"traceutil/trace.go:171","msg":"trace[767149175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1839; }","duration":"136.286837ms","start":"2026-10-20T16:42:27.647834Z","end":"2026-10-20T16:42:27.784121Z","steps":["trace[767149175] 'agreement among raft nodes before linearized reading' (duration: 136.090793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:42:27.784223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.107729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11251"} {"level":"info","ts":"2026-10-20T16:42:27.784272Z","caller":"traceutil/trace.go:171","msg":"trace[1482059112] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1839; }","duration":"124.18686ms","start":"2026-10-20T16:42:27.660072Z","end":"2026-10-20T16:42:27.784259Z","steps":["trace[1482059112] 'agreement among raft nodes before linearized reading' (duration: 123.996536ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:28.140224Z","caller":"traceutil/trace.go:171","msg":"trace[142665216] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"343.34309ms","start":"2026-10-20T16:42:27.796853Z","end":"2026-10-20T16:42:28.140196Z","steps":["trace[142665216] 'process raft request' (duration: 343.194937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:42:28.140395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:42:27.79683Z","time spent":"343.470183ms","remote":"127.0.0.1:36022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T16:42:28.158756Z","caller":"traceutil/trace.go:171","msg":"trace[1352748837] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"241.855229ms","start":"2026-10-20T16:42:27.916872Z","end":"2026-10-20T16:42:28.158727Z","steps":["trace[1352748837] 'process raft request' (duration: 241.621174ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:28.313512Z","caller":"traceutil/trace.go:171","msg":"trace[1381810414] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"167.290669ms","start":"2026-10-20T16:42:28.146187Z","end":"2026-10-20T16:42:28.313478Z","steps":["trace[1381810414] 'process raft request' (duration: 128.70862ms)","trace[1381810414] 'compare' (duration: 37.859663ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:42:28.313955Z","caller":"traceutil/trace.go:171","msg":"trace[195213750] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"167.663407ms","start":"2026-10-20T16:42:28.146277Z","end":"2026-10-20T16:42:28.31394Z","steps":["trace[195213750] 'process raft request' (duration: 167.300009ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:28.314079Z","caller":"traceutil/trace.go:171","msg":"trace[872305248] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"167.586306ms","start":"2026-10-20T16:42:28.146462Z","end":"2026-10-20T16:42:28.314048Z","steps":["trace[872305248] 'process raft request' (duration: 167.234038ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:28.314752Z","caller":"traceutil/trace.go:171","msg":"trace[166247884] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"167.584866ms","start":"2026-10-20T16:42:28.147151Z","end":"2026-10-20T16:42:28.314736Z","steps":["trace[166247884] 'process raft request' (duration: 166.818989ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:28.315077Z","caller":"traceutil/trace.go:171","msg":"trace[1013698447] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1913; }","duration":"156.130263ms","start":"2026-10-20T16:42:28.158871Z","end":"2026-10-20T16:42:28.315001Z","steps":["trace[1013698447] 'read index received' (duration: 116.001551ms)","trace[1013698447] 'applied index is now lower than readState.Index' (duration: 40.127042ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:42:28.315291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.133641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e5893ee-75e1-42f9-8317-306d5f1914e2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-20T16:42:28.31533Z","caller":"traceutil/trace.go:171","msg":"trace[1741509824] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7e5893ee-75e1-42f9-8317-306d5f1914e2; range_end:; response_count:1; response_revision:1846; }","duration":"170.208002ms","start":"2026-10-20T16:42:28.14511Z","end":"2026-10-20T16:42:28.315318Z","steps":["trace[1741509824] 'agreement among raft nodes before linearized reading' (duration: 170.041279ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:28.707921Z","caller":"traceutil/trace.go:171","msg":"trace[1148648039] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"377.714676ms","start":"2026-10-20T16:42:28.330178Z","end":"2026-10-20T16:42:28.707893Z","steps":["trace[1148648039] 'read index received' (duration: 312.671846ms)","trace[1148648039] 'applied index is now lower than readState.Index' (duration: 65.04179ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:42:28.707978Z","caller":"traceutil/trace.go:171","msg":"trace[8309283] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"385.066548ms","start":"2026-10-20T16:42:28.322878Z","end":"2026-10-20T16:42:28.707945Z","steps":["trace[8309283] 'process raft request' (duration: 320.002277ms)","trace[8309283] 'compare' (duration: 64.484228ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:42:28.708102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.406699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-20T16:42:28.708128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:42:28.322858Z","time spent":"385.1838ms","remote":"127.0.0.1:36022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T16:42:28.708159Z","caller":"traceutil/trace.go:171","msg":"trace[1613661517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"347.498342ms","start":"2026-10-20T16:42:28.360648Z","end":"2026-10-20T16:42:28.708147Z","steps":["trace[1613661517] 'agreement among raft nodes before linearized reading' (duration: 347.397489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:42:28.70819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:42:28.360639Z","time spent":"347.544182ms","remote":"127.0.0.1:35844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-20T16:42:28.708087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.8823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-10-20T16:42:28.708215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.782423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e5893ee-75e1-42f9-8317-306d5f1914e2\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-10-20T16:42:28.708251Z","caller":"traceutil/trace.go:171","msg":"trace[478526050] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1848; }","duration":"378.096744ms","start":"2026-10-20T16:42:28.330131Z","end":"2026-10-20T16:42:28.708228Z","steps":["trace[478526050] 'agreement among raft nodes before linearized reading' (duration: 377.846739ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:28.708301Z","caller":"traceutil/trace.go:171","msg":"trace[1078083454] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7e5893ee-75e1-42f9-8317-306d5f1914e2; range_end:; response_count:1; response_revision:1848; }","duration":"214.891736ms","start":"2026-10-20T16:42:28.49339Z","end":"2026-10-20T16:42:28.708281Z","steps":["trace[1078083454] 'agreement among raft nodes before linearized reading' (duration: 214.721032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:42:28.708312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:42:28.330112Z","time spent":"378.181356ms","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-10-20T16:42:28.708244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.417321ms","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-20T16:42:28.708435Z","caller":"traceutil/trace.go:171","msg":"trace[1567515882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1848; }","duration":"257.626556ms","start":"2026-10-20T16:42:28.450799Z","end":"2026-10-20T16:42:28.708425Z","steps":["trace[1567515882] 'agreement among raft nodes before linearized reading' (duration: 257.35026ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:41.005532Z","caller":"traceutil/trace.go:171","msg":"trace[1545068365] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"121.660216ms","start":"2026-10-20T16:42:40.88385Z","end":"2026-10-20T16:42:41.00551Z","steps":["trace[1545068365] 'read index received' (duration: 121.583144ms)","trace[1545068365] 'applied index is now lower than readState.Index' (duration: 76.182µs)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:42:41.005724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.86052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-20T16:42:41.00579Z","caller":"traceutil/trace.go:171","msg":"trace[900451649] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1949; }","duration":"121.974072ms","start":"2026-10-20T16:42:40.8838Z","end":"2026-10-20T16:42:41.005774Z","steps":["trace[900451649] 'agreement among raft nodes before linearized reading' (duration: 121.812639ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:42:41.005718Z","caller":"traceutil/trace.go:171","msg":"trace[781696660] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"190.045709ms","start":"2026-10-20T16:42:40.815529Z","end":"2026-10-20T16:42:41.005575Z","steps":["trace[781696660] 'process raft request' (duration: 189.845085ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:43:07.346385Z","caller":"traceutil/trace.go:171","msg":"trace[1942057700] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"112.103418ms","start":"2026-10-20T16:43:07.234244Z","end":"2026-10-20T16:43:07.346348Z","steps":["trace[1942057700] 'process raft request' (duration: 111.886003ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:43:19.314359Z","caller":"traceutil/trace.go:171","msg":"trace[653845155] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"119.787238ms","start":"2026-10-20T16:43:19.194547Z","end":"2026-10-20T16:43:19.314334Z","steps":["trace[653845155] 'read index received' (duration: 119.652355ms)","trace[653845155] 'applied index is now lower than readState.Index' (duration: 133.823µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:43:19.314423Z","caller":"traceutil/trace.go:171","msg":"trace[1793981877] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"149.476502ms","start":"2026-10-20T16:43:19.164912Z","end":"2026-10-20T16:43:19.314388Z","steps":["trace[1793981877] 'process raft request' (duration: 149.260088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:43:19.314617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.039083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-20T16:43:19.314662Z","caller":"traceutil/trace.go:171","msg":"trace[148809005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2173; }","duration":"120.151226ms","start":"2026-10-20T16:43:19.194496Z","end":"2026-10-20T16:43:19.314648Z","steps":["trace[148809005] 'agreement among raft nodes before linearized reading' (duration: 119.973992ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:43:19.534551Z","caller":"traceutil/trace.go:171","msg":"trace[1655547662] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"215.825324ms","start":"2026-10-20T16:43:19.318694Z","end":"2026-10-20T16:43:19.534519Z","steps":["trace[1655547662] 'process raft request' (duration: 215.21319ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:43:19.534916Z","caller":"traceutil/trace.go:171","msg":"trace[733027908] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2257; }","duration":"103.444388ms","start":"2026-10-20T16:43:19.431447Z","end":"2026-10-20T16:43:19.534892Z","steps":["trace[733027908] 'read index received' (duration: 102.464826ms)","trace[733027908] 'applied index is now lower than readState.Index' (duration: 978.432µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:43:19.534992Z","caller":"traceutil/trace.go:171","msg":"trace[873011445] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"148.568572ms","start":"2026-10-20T16:43:19.386386Z","end":"2026-10-20T16:43:19.534954Z","steps":["trace[873011445] 'process raft request' (duration: 148.400909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:43:19.535103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.643423ms","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-20T16:43:19.535142Z","caller":"traceutil/trace.go:171","msg":"trace[517100996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2175; }","duration":"103.729204ms","start":"2026-10-20T16:43:19.431403Z","end":"2026-10-20T16:43:19.535132Z","steps":["trace[517100996] 'agreement among raft nodes before linearized reading' (duration: 103.584981ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:44:43.790037Z","caller":"traceutil/trace.go:171","msg":"trace[1515830360] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"158.806634ms","start":"2026-10-20T16:44:43.631204Z","end":"2026-10-20T16:44:43.79001Z","steps":["trace[1515830360] 'process raft request' (duration: 158.614169ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:44:44.066291Z","caller":"traceutil/trace.go:171","msg":"trace[783463336] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"225.920714ms","start":"2026-10-20T16:44:43.840341Z","end":"2026-10-20T16:44:44.066262Z","steps":["trace[783463336] 'process raft request' (duration: 141.87431ms)","trace[783463336] 'compare' (duration: 83.578694ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:44:44.066739Z","caller":"traceutil/trace.go:171","msg":"trace[324546069] linearizableReadLoop","detail":"{readStateIndex:2932; appliedIndex:2931; }","duration":"173.667531ms","start":"2026-10-20T16:44:43.893037Z","end":"2026-10-20T16:44:44.066704Z","steps":["trace[324546069] 'read index received' (duration: 89.128126ms)","trace[324546069] 'applied index is now lower than readState.Index' (duration: 84.533295ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:44:44.066905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.843866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-20T16:44:44.066987Z","caller":"traceutil/trace.go:171","msg":"trace[2008153592] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2824; }","duration":"173.976138ms","start":"2026-10-20T16:44:43.89299Z","end":"2026-10-20T16:44:44.066966Z","steps":["trace[2008153592] 'agreement among raft nodes before linearized reading' (duration: 173.853625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:44:44.067039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.26341ms","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-20T16:44:44.067083Z","caller":"traceutil/trace.go:171","msg":"trace[1490858256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2824; }","duration":"123.364982ms","start":"2026-10-20T16:44:43.943706Z","end":"2026-10-20T16:44:44.067071Z","steps":["trace[1490858256] 'agreement among raft nodes before linearized reading' (duration: 123.164988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:44:44.067146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.307612ms","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-20T16:44:44.067246Z","caller":"traceutil/trace.go:171","msg":"trace[982029025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2824; }","duration":"114.434444ms","start":"2026-10-20T16:44:43.952788Z","end":"2026-10-20T16:44:44.067222Z","steps":["trace[982029025] 'agreement among raft nodes before linearized reading' (duration: 114.126038ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:44:44.174563Z","caller":"traceutil/trace.go:171","msg":"trace[1509195323] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"102.718356ms","start":"2026-10-20T16:44:44.071821Z","end":"2026-10-20T16:44:44.17454Z","steps":["trace[1509195323] 'process raft request' (duration: 100.434576ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:47:25.541469Z","caller":"traceutil/trace.go:171","msg":"trace[2114333719] linearizableReadLoop","detail":"{readStateIndex:3982; appliedIndex:3981; }","duration":"107.903452ms","start":"2026-10-20T16:47:25.43354Z","end":"2026-10-20T16:47:25.541443Z","steps":["trace[2114333719] 'read index received' (duration: 107.742108ms)","trace[2114333719] 'applied index is now lower than readState.Index' (duration: 160.104µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:47:25.541567Z","caller":"traceutil/trace.go:171","msg":"trace[255595466] transaction","detail":"{read_only:false; response_revision:3834; number_of_response:1; }","duration":"114.926507ms","start":"2026-10-20T16:47:25.426578Z","end":"2026-10-20T16:47:25.541505Z","steps":["trace[255595466] 'process raft request' (duration: 114.707642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:47:25.541704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.172287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-10-20T16:47:25.541751Z","caller":"traceutil/trace.go:171","msg":"trace[2032694749] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3834; }","duration":"108.269219ms","start":"2026-10-20T16:47:25.433466Z","end":"2026-10-20T16:47:25.541736Z","steps":["trace[2032694749] 'agreement among raft nodes before linearized reading' (duration: 108.123926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:48:01.503566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.356675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T16:48:01.503743Z","caller":"traceutil/trace.go:171","msg":"trace[1779331586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4065; }","duration":"142.630071ms","start":"2026-10-20T16:48:01.36107Z","end":"2026-10-20T16:48:01.5037Z","steps":["trace[1779331586] 'range keys from in-memory index tree' (duration: 142.199392ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:48:01.633994Z","caller":"traceutil/trace.go:171","msg":"trace[1326160563] transaction","detail":"{read_only:false; response_revision:4067; number_of_response:1; }","duration":"127.472135ms","start":"2026-10-20T16:48:01.506478Z","end":"2026-10-20T16:48:01.633951Z","steps":["trace[1326160563] 'process raft request' (duration: 119.200431ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:48:01.634131Z","caller":"traceutil/trace.go:171","msg":"trace[1201662316] transaction","detail":"{read_only:false; response_revision:4068; number_of_response:1; }","duration":"126.984904ms","start":"2026-10-20T16:48:01.507117Z","end":"2026-10-20T16:48:01.634102Z","steps":["trace[1201662316] 'process raft request' (duration: 126.679977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:48:01.634212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.141258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T16:48:01.634252Z","caller":"traceutil/trace.go:171","msg":"trace[1994249383] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:4068; }","duration":"113.200799ms","start":"2026-10-20T16:48:01.521043Z","end":"2026-10-20T16:48:01.634244Z","steps":["trace[1994249383] 'agreement among raft nodes before linearized reading' (duration: 113.101567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:48:01.634611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.785206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T16:48:01.634717Z","caller":"traceutil/trace.go:171","msg":"trace[1920459459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4068; }","duration":"129.912119ms","start":"2026-10-20T16:48:01.504783Z","end":"2026-10-20T16:48:01.634695Z","steps":["trace[1920459459] 'agreement among raft nodes before linearized reading' (duration: 129.449729ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:24.819013Z","caller":"traceutil/trace.go:171","msg":"trace[2004701585] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"163.790829ms","start":"2026-10-20T16:49:24.655195Z","end":"2026-10-20T16:49:24.818986Z","steps":["trace[2004701585] 'process raft request' (duration: 163.527693ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:24.948923Z","caller":"traceutil/trace.go:171","msg":"trace[2135189833] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"288.894218ms","start":"2026-10-20T16:49:24.659968Z","end":"2026-10-20T16:49:24.948862Z","steps":["trace[2135189833] 'process raft request' (duration: 264.836483ms)","trace[2135189833] 'compare' (duration: 23.767749ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:49:24.948998Z","caller":"traceutil/trace.go:171","msg":"trace[733735370] linearizableReadLoop","detail":"{readStateIndex:5231; appliedIndex:5229; }","duration":"273.724511ms","start":"2026-10-20T16:49:24.675228Z","end":"2026-10-20T16:49:24.948952Z","steps":["trace[733735370] 'read index received' (duration: 143.529109ms)","trace[733735370] 'applied index is now lower than readState.Index' (duration: 130.191502ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:49:24.949301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.045278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93c2d1ff-a5c1-435a-b984-798a6069a4d3\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-10-20T16:49:24.949407Z","caller":"traceutil/trace.go:171","msg":"trace[1903395091] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93c2d1ff-a5c1-435a-b984-798a6069a4d3; range_end:; response_count:1; response_revision:5040; }","duration":"274.164021ms","start":"2026-10-20T16:49:24.675216Z","end":"2026-10-20T16:49:24.94938Z","steps":["trace[1903395091] 'agreement among raft nodes before linearized reading' (duration: 273.863224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:49:24.949424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.841902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93c2d1ff-a5c1-435a-b984-798a6069a4d3\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-10-20T16:49:24.949499Z","caller":"traceutil/trace.go:171","msg":"trace[1881629455] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93c2d1ff-a5c1-435a-b984-798a6069a4d3; range_end:; response_count:1; response_revision:5040; }","duration":"151.002075ms","start":"2026-10-20T16:49:24.798482Z","end":"2026-10-20T16:49:24.949484Z","steps":["trace[1881629455] 'agreement among raft nodes before linearized reading' (duration: 150.670258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:49:24.949602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.241126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:71911"} {"level":"info","ts":"2026-10-20T16:49:24.949695Z","caller":"traceutil/trace.go:171","msg":"trace[189258855] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:5040; }","duration":"122.344798ms","start":"2026-10-20T16:49:24.827327Z","end":"2026-10-20T16:49:24.949672Z","steps":["trace[189258855] 'agreement among raft nodes before linearized reading' (duration: 121.947269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:49:24.949803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.658805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-20T16:49:24.949855Z","caller":"traceutil/trace.go:171","msg":"trace[1578572968] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5040; }","duration":"122.763217ms","start":"2026-10-20T16:49:24.827077Z","end":"2026-10-20T16:49:24.94984Z","steps":["trace[1578572968] 'agreement among raft nodes before linearized reading' (duration: 122.521741ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:31.119988Z","caller":"traceutil/trace.go:171","msg":"trace[2126683184] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"120.326762ms","start":"2026-10-20T16:49:30.999632Z","end":"2026-10-20T16:49:31.119958Z","steps":["trace[2126683184] 'process raft request' (duration: 120.24464ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:31.119959Z","caller":"traceutil/trace.go:171","msg":"trace[299009449] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5452; }","duration":"148.470628ms","start":"2026-10-20T16:49:30.971468Z","end":"2026-10-20T16:49:31.119938Z","steps":["trace[299009449] 'read index received' (duration: 92.322571ms)","trace[299009449] 'applied index is now lower than readState.Index' (duration: 56.146037ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:49:31.120218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.722913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-gqv47-tls\" ","response":"range_response_count:1 size:2184"} {"level":"warn","ts":"2026-10-20T16:49:31.12027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.378438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T16:49:31.120274Z","caller":"traceutil/trace.go:171","msg":"trace[510251630] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-gqv47-tls; range_end:; response_count:1; response_revision:5254; }","duration":"148.866007ms","start":"2026-10-20T16:49:30.971393Z","end":"2026-10-20T16:49:31.120259Z","steps":["trace[510251630] 'agreement among raft nodes before linearized reading' (duration: 148.654092ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:31.120293Z","caller":"traceutil/trace.go:171","msg":"trace[96052511] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5254; }","duration":"122.433639ms","start":"2026-10-20T16:49:30.997848Z","end":"2026-10-20T16:49:31.120282Z","steps":["trace[96052511] 'agreement among raft nodes before linearized reading' (duration: 122.405159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:49:31.120211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.140724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-954a07c9-f783-490f-ac5a-e94ef2c7414b\" ","response":"range_response_count:1 size:3715"} {"level":"info","ts":"2026-10-20T16:49:31.120501Z","caller":"traceutil/trace.go:171","msg":"trace[934281650] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-954a07c9-f783-490f-ac5a-e94ef2c7414b; range_end:; response_count:1; response_revision:5254; }","duration":"136.492342ms","start":"2026-10-20T16:49:30.983998Z","end":"2026-10-20T16:49:31.120491Z","steps":["trace[934281650] 'agreement among raft nodes before linearized reading' (duration: 136.158845ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:31.120758Z","caller":"traceutil/trace.go:171","msg":"trace[1527122151] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"223.896184ms","start":"2026-10-20T16:49:30.895988Z","end":"2026-10-20T16:49:31.119884Z","steps":["trace[1527122151] 'process raft request' (duration: 167.803848ms)","trace[1527122151] 'compare' (duration: 55.862541ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:49:31.307685Z","caller":"traceutil/trace.go:171","msg":"trace[1808898915] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5256; }","duration":"157.346985ms","start":"2026-10-20T16:49:31.150321Z","end":"2026-10-20T16:49:31.307668Z","steps":["trace[1808898915] 'process raft request' (duration: 157.322885ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:31.30791Z","caller":"traceutil/trace.go:171","msg":"trace[2014900816] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"163.658246ms","start":"2026-10-20T16:49:31.144244Z","end":"2026-10-20T16:49:31.307903Z","steps":["trace[2014900816] 'process raft request' (duration: 163.370309ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:31.307884Z","caller":"traceutil/trace.go:171","msg":"trace[1473156799] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"165.060206ms","start":"2026-10-20T16:49:31.142781Z","end":"2026-10-20T16:49:31.307841Z","steps":["trace[1473156799] 'process raft request' (duration: 122.944111ms)","trace[1473156799] 'compare' (duration: 41.592104ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:49:31.62587Z","caller":"traceutil/trace.go:171","msg":"trace[226254140] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"131.618444ms","start":"2026-10-20T16:49:31.494218Z","end":"2026-10-20T16:49:31.625836Z","steps":["trace[226254140] 'process raft request' (duration: 67.297465ms)","trace[226254140] 'compare' (duration: 64.075464ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:49:40.548118Z","caller":"traceutil/trace.go:171","msg":"trace[2001228417] linearizableReadLoop","detail":"{readStateIndex:5651; appliedIndex:5650; }","duration":"124.177068ms","start":"2026-10-20T16:49:40.423912Z","end":"2026-10-20T16:49:40.548089Z","steps":["trace[2001228417] 'read index received' (duration: 98.977728ms)","trace[2001228417] 'applied index is now lower than readState.Index' (duration: 25.1857ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:49:40.548371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.437995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-20T16:49:40.548426Z","caller":"traceutil/trace.go:171","msg":"trace[25090701] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5445; }","duration":"124.534976ms","start":"2026-10-20T16:49:40.423875Z","end":"2026-10-20T16:49:40.54841Z","steps":["trace[25090701] 'agreement among raft nodes before linearized reading' (duration: 124.342182ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:40.548549Z","caller":"traceutil/trace.go:171","msg":"trace[1457426625] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"144.824747ms","start":"2026-10-20T16:49:40.403685Z","end":"2026-10-20T16:49:40.548509Z","steps":["trace[1457426625] 'process raft request' (duration: 119.28176ms)","trace[1457426625] 'compare' (duration: 24.988285ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:49:46.482179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.009296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-20T16:49:46.482257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.615202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T16:49:46.482369Z","caller":"traceutil/trace.go:171","msg":"trace[847669333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5522; }","duration":"121.787755ms","start":"2026-10-20T16:49:46.360558Z","end":"2026-10-20T16:49:46.482346Z","steps":["trace[847669333] 'range keys from in-memory index tree' (duration: 121.489789ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:46.482283Z","caller":"traceutil/trace.go:171","msg":"trace[1782938770] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5522; }","duration":"192.177159ms","start":"2026-10-20T16:49:46.290078Z","end":"2026-10-20T16:49:46.482255Z","steps":["trace[1782938770] 'count revisions from in-memory index tree' (duration: 191.920163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:49:46.48251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.946111ms","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-20T16:49:46.48255Z","caller":"traceutil/trace.go:171","msg":"trace[1860522161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5522; }","duration":"204.022143ms","start":"2026-10-20T16:49:46.278514Z","end":"2026-10-20T16:49:46.482536Z","steps":["trace[1860522161] 'range keys from in-memory index tree' (duration: 203.674815ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:49:46.603118Z","caller":"traceutil/trace.go:171","msg":"trace[1458527580] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"116.11305ms","start":"2026-10-20T16:49:46.486982Z","end":"2026-10-20T16:49:46.603095Z","steps":["trace[1458527580] 'process raft request' (duration: 115.683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:49:55.406762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.098349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-20T16:49:55.406931Z","caller":"traceutil/trace.go:171","msg":"trace[1214736611] linearizableReadLoop","detail":"{readStateIndex:5903; appliedIndex:5902; }","duration":"147.553628ms","start":"2026-10-20T16:49:55.259351Z","end":"2026-10-20T16:49:55.406904Z","steps":["trace[1214736611] 'read index received' (duration: 31.03883ms)","trace[1214736611] 'applied index is now lower than readState.Index' (duration: 116.513188ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:49:55.40697Z","caller":"traceutil/trace.go:171","msg":"trace[583667690] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"167.570752ms","start":"2026-10-20T16:49:55.239348Z","end":"2026-10-20T16:49:55.406918Z","steps":["trace[583667690] 'process raft request' (duration: 51.167537ms)","trace[583667690] 'compare' (duration: 115.868963ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:49:55.407147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.759862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T16:49:55.407252Z","caller":"traceutil/trace.go:171","msg":"trace[1228410781] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5693; }","duration":"147.956196ms","start":"2026-10-20T16:49:55.259271Z","end":"2026-10-20T16:49:55.407227Z","steps":["trace[1228410781] 'agreement among raft nodes before linearized reading' (duration: 147.742571ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:50:04.923527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"info","ts":"2026-10-20T16:50:05.017496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"90.180203ms","hash":505678307,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":32509952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-20T16:50:05.01759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":505678307,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-10-20T16:50:07.648523Z","caller":"traceutil/trace.go:171","msg":"trace[175073674] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"150.111924ms","start":"2026-10-20T16:50:07.498371Z","end":"2026-10-20T16:50:07.648483Z","steps":["trace[175073674] 'process raft request' (duration: 149.899649ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:50:07.664219Z","caller":"traceutil/trace.go:171","msg":"trace[1353185014] transaction","detail":"{read_only:false; response_revision:5869; number_of_response:1; }","duration":"140.428719ms","start":"2026-10-20T16:50:07.523758Z","end":"2026-10-20T16:50:07.664187Z","steps":["trace[1353185014] 'process raft request' (duration: 140.272025ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:55:04.9324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5842} {"level":"info","ts":"2026-10-20T16:55:05.034738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5842,"took":"99.86478ms","hash":2170617589,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":26091520,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-20T16:55:05.034814Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2170617589,"revision":5842,"compact-revision":2941} {"level":"info","ts":"2026-10-20T16:56:26.457914Z","caller":"traceutil/trace.go:171","msg":"trace[570929360] linearizableReadLoop","detail":"{readStateIndex:8957; appliedIndex:8956; }","duration":"392.410621ms","start":"2026-10-20T16:56:26.065477Z","end":"2026-10-20T16:56:26.457887Z","steps":["trace[570929360] 'read index received' (duration: 392.215006ms)","trace[570929360] 'applied index is now lower than readState.Index' (duration: 194.515µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:56:26.458241Z","caller":"traceutil/trace.go:171","msg":"trace[1114745605] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"439.222126ms","start":"2026-10-20T16:56:26.018968Z","end":"2026-10-20T16:56:26.45819Z","steps":["trace[1114745605] 'process raft request' (duration: 438.776707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:56:26.458359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:56:26.018942Z","time spent":"439.335878ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-20T16:56:26.458499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.032994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-20T16:56:26.458533Z","caller":"traceutil/trace.go:171","msg":"trace[1827659668] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8651; }","duration":"393.112626ms","start":"2026-10-20T16:56:26.065412Z","end":"2026-10-20T16:56:26.458524Z","steps":["trace[1827659668] 'agreement among raft nodes before linearized reading' (duration: 393.054995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:56:26.458568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:56:26.065386Z","time spent":"393.172348ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-20T16:56:26.458805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.685772ms","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-20T16:56:26.458888Z","caller":"traceutil/trace.go:171","msg":"trace[1116634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8651; }","duration":"357.835076ms","start":"2026-10-20T16:56:26.101033Z","end":"2026-10-20T16:56:26.458868Z","steps":["trace[1116634] 'agreement among raft nodes before linearized reading' (duration: 357.630922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:56:26.458929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:56:26.101002Z","time spent":"357.918728ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-20T16:56:26.459033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.246774ms","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-20T16:56:26.459088Z","caller":"traceutil/trace.go:171","msg":"trace[597471953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8651; }","duration":"122.365397ms","start":"2026-10-20T16:56:26.336713Z","end":"2026-10-20T16:56:26.459079Z","steps":["trace[597471953] 'agreement among raft nodes before linearized reading' (duration: 122.211514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:56:26.459161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.421021ms","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-20T16:56:26.459197Z","caller":"traceutil/trace.go:171","msg":"trace[704473443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8651; }","duration":"133.498123ms","start":"2026-10-20T16:56:26.325689Z","end":"2026-10-20T16:56:26.459187Z","steps":["trace[704473443] 'agreement among raft nodes before linearized reading' (duration: 133.38763ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:56:28.034661Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002308.wal"} {"level":"warn","ts":"2026-10-20T16:56:38.597437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.524247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-20T16:56:38.597614Z","caller":"traceutil/trace.go:171","msg":"trace[339552049] transaction","detail":"{read_only:false; response_revision:8715; number_of_response:1; }","duration":"331.527794ms","start":"2026-10-20T16:56:38.26606Z","end":"2026-10-20T16:56:38.597587Z","steps":["trace[339552049] 'process raft request' (duration: 87.730161ms)","trace[339552049] 'compare' (duration: 243.288742ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:56:38.597703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:56:38.266036Z","time spent":"331.624186ms","remote":"127.0.0.1:35862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T16:56:38.643036Z","caller":"traceutil/trace.go:171","msg":"trace[1543213608] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"375.332723ms","start":"2026-10-20T16:56:38.267643Z","end":"2026-10-20T16:56:38.642976Z","steps":["trace[1543213608] 'process raft request' (duration: 375.141839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:56:38.643341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T16:56:38.267619Z","time spent":"375.539578ms","remote":"127.0.0.1:35914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T16:57:09.045974Z","caller":"traceutil/trace.go:171","msg":"trace[1593574410] linearizableReadLoop","detail":"{readStateIndex:9252; appliedIndex:9251; }","duration":"213.154006ms","start":"2026-10-20T16:57:08.832792Z","end":"2026-10-20T16:57:09.045946Z","steps":["trace[1593574410] 'read index received' (duration: 212.938471ms)","trace[1593574410] 'applied index is now lower than readState.Index' (duration: 214.635µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:57:09.046357Z","caller":"traceutil/trace.go:171","msg":"trace[97202729] transaction","detail":"{read_only:false; response_revision:8934; number_of_response:1; }","duration":"217.336489ms","start":"2026-10-20T16:57:08.829001Z","end":"2026-10-20T16:57:09.046337Z","steps":["trace[97202729] 'process raft request' (duration: 216.786416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:57:09.046742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.940243ms","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-20T16:57:09.046796Z","caller":"traceutil/trace.go:171","msg":"trace[376123613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8934; }","duration":"214.041335ms","start":"2026-10-20T16:57:08.832742Z","end":"2026-10-20T16:57:09.046783Z","steps":["trace[376123613] 'agreement among raft nodes before linearized reading' (duration: 213.859581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:57:09.04706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.444118ms","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-20T16:57:09.047112Z","caller":"traceutil/trace.go:171","msg":"trace[615216406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8934; }","duration":"211.517589ms","start":"2026-10-20T16:57:08.835583Z","end":"2026-10-20T16:57:09.0471Z","steps":["trace[615216406] 'agreement among raft nodes before linearized reading' (duration: 211.399077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T16:57:09.047327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.148241ms","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-20T16:57:09.047416Z","caller":"traceutil/trace.go:171","msg":"trace[1492977927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8934; }","duration":"206.281134ms","start":"2026-10-20T16:57:08.841111Z","end":"2026-10-20T16:57:09.047392Z","steps":["trace[1492977927] 'agreement among raft nodes before linearized reading' (duration: 206.041228ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:57:09.329545Z","caller":"traceutil/trace.go:171","msg":"trace[1334007022] linearizableReadLoop","detail":"{readStateIndex:9255; appliedIndex:9252; }","duration":"216.910518ms","start":"2026-10-20T16:57:09.112616Z","end":"2026-10-20T16:57:09.329527Z","steps":["trace[1334007022] 'read index received' (duration: 193.94503ms)","trace[1334007022] 'applied index is now lower than readState.Index' (duration: 22.964728ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T16:57:09.329585Z","caller":"traceutil/trace.go:171","msg":"trace[1326570986] transaction","detail":"{read_only:false; response_revision:8935; number_of_response:1; }","duration":"276.497527ms","start":"2026-10-20T16:57:09.053057Z","end":"2026-10-20T16:57:09.329555Z","steps":["trace[1326570986] 'process raft request' (duration: 253.441847ms)","trace[1326570986] 'compare' (duration: 22.871536ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T16:57:09.329677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.048091ms","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-20T16:57:09.329701Z","caller":"traceutil/trace.go:171","msg":"trace[1613605001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8937; }","duration":"217.130383ms","start":"2026-10-20T16:57:09.112565Z","end":"2026-10-20T16:57:09.329696Z","steps":["trace[1613605001] 'agreement among raft nodes before linearized reading' (duration: 217.027391ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:57:09.329779Z","caller":"traceutil/trace.go:171","msg":"trace[2053790290] transaction","detail":"{read_only:false; response_revision:8936; number_of_response:1; }","duration":"276.341104ms","start":"2026-10-20T16:57:09.05343Z","end":"2026-10-20T16:57:09.329771Z","steps":["trace[2053790290] 'process raft request' (duration: 276.047697ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:57:09.329823Z","caller":"traceutil/trace.go:171","msg":"trace[2004215174] transaction","detail":"{read_only:false; response_revision:8937; number_of_response:1; }","duration":"273.600563ms","start":"2026-10-20T16:57:09.056209Z","end":"2026-10-20T16:57:09.32981Z","steps":["trace[2004215174] 'process raft request' (duration: 273.294846ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:57:20.325745Z","caller":"traceutil/trace.go:171","msg":"trace[572511500] transaction","detail":"{read_only:false; response_revision:8997; number_of_response:1; }","duration":"159.256833ms","start":"2026-10-20T16:57:20.166461Z","end":"2026-10-20T16:57:20.325717Z","steps":["trace[572511500] 'process raft request' (duration: 159.085019ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:57:21.251596Z","caller":"traceutil/trace.go:171","msg":"trace[1596329230] transaction","detail":"{read_only:false; response_revision:8998; number_of_response:1; }","duration":"126.156181ms","start":"2026-10-20T16:57:21.125413Z","end":"2026-10-20T16:57:21.251569Z","steps":["trace[1596329230] 'process raft request' (duration: 125.968467ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T16:58:23.977493Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"548b318b3528a14c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-20T16:58:23.986297Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-20T16:58:23.986505Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-20T17:00:04.939179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7999} {"level":"info","ts":"2026-10-20T17:00:05.010844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7999,"took":"69.376525ms","hash":2650781377,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":27942912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-20T17:00:05.010913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2650781377,"revision":7999,"compact-revision":5842} {"level":"info","ts":"2026-10-20T17:04:31.505302Z","caller":"traceutil/trace.go:171","msg":"trace[2104812149] linearizableReadLoop","detail":"{readStateIndex:12727; appliedIndex:12726; }","duration":"102.335821ms","start":"2026-10-20T17:04:31.402939Z","end":"2026-10-20T17:04:31.505275Z","steps":["trace[2104812149] 'read index received' (duration: 102.025595ms)","trace[2104812149] 'applied index is now lower than readState.Index' (duration: 308.656µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T17:04:31.50536Z","caller":"traceutil/trace.go:171","msg":"trace[2044860950] transaction","detail":"{read_only:false; response_revision:12302; number_of_response:1; }","duration":"130.607976ms","start":"2026-10-20T17:04:31.374718Z","end":"2026-10-20T17:04:31.505326Z","steps":["trace[2044860950] 'process raft request' (duration: 130.35539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:04:31.505473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.510305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T17:04:31.505579Z","caller":"traceutil/trace.go:171","msg":"trace[569265597] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12302; }","duration":"102.659678ms","start":"2026-10-20T17:04:31.402909Z","end":"2026-10-20T17:04:31.505569Z","steps":["trace[569265597] 'agreement among raft nodes before linearized reading' (duration: 102.511015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:04:31.505566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.367292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-20T17:04:31.505611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.003194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T17:04:31.505655Z","caller":"traceutil/trace.go:171","msg":"trace[790252286] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12302; }","duration":"102.508504ms","start":"2026-10-20T17:04:31.403124Z","end":"2026-10-20T17:04:31.505632Z","steps":["trace[790252286] 'agreement among raft nodes before linearized reading' (duration: 102.351431ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:04:31.505646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.618145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-10-20T17:04:31.505694Z","caller":"traceutil/trace.go:171","msg":"trace[2011385367] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12302; }","duration":"102.153797ms","start":"2026-10-20T17:04:31.40352Z","end":"2026-10-20T17:04:31.505674Z","steps":["trace[2011385367] 'agreement among raft nodes before linearized reading' (duration: 101.996604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:04:31.505672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.238178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T17:04:31.505722Z","caller":"traceutil/trace.go:171","msg":"trace[1931362872] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12302; }","duration":"101.714497ms","start":"2026-10-20T17:04:31.403989Z","end":"2026-10-20T17:04:31.505703Z","steps":["trace[1931362872] 'agreement among raft nodes before linearized reading' (duration: 101.530263ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:04:31.505774Z","caller":"traceutil/trace.go:171","msg":"trace[1787957236] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12302; }","duration":"102.372572ms","start":"2026-10-20T17:04:31.403373Z","end":"2026-10-20T17:04:31.505746Z","steps":["trace[1787957236] 'agreement among raft nodes before linearized reading' (duration: 102.176167ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:05:04.94677Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10364} {"level":"info","ts":"2026-10-20T17:05:05.047259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10364,"took":"98.030736ms","hash":1433501211,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":25833472,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-20T17:05:05.047336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1433501211,"revision":10364,"compact-revision":7999} {"level":"info","ts":"2026-10-20T17:10:04.957253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12545} {"level":"info","ts":"2026-10-20T17:10:05.047126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12545,"took":"86.313245ms","hash":2342247713,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":27705344,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-20T17:10:05.047207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2342247713,"revision":12545,"compact-revision":10364} {"level":"info","ts":"2026-10-20T17:11:31.456558Z","caller":"traceutil/trace.go:171","msg":"trace[2047073400] transaction","detail":"{read_only:false; response_revision:15101; number_of_response:1; }","duration":"451.398636ms","start":"2026-10-20T17:11:31.005127Z","end":"2026-10-20T17:11:31.456525Z","steps":["trace[2047073400] 'process raft request' (duration: 451.243972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:31.456727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:11:31.005075Z","time spent":"451.56927ms","remote":"127.0.0.1:36022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T17:11:31.473726Z","caller":"traceutil/trace.go:171","msg":"trace[1059745817] transaction","detail":"{read_only:false; response_revision:15102; number_of_response:1; }","duration":"426.711421ms","start":"2026-10-20T17:11:31.046984Z","end":"2026-10-20T17:11:31.473696Z","steps":["trace[1059745817] 'process raft request' (duration: 426.336632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:31.473845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:11:31.04695Z","time spent":"426.833984ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T17:11:31.473815Z","caller":"traceutil/trace.go:171","msg":"trace[950641629] linearizableReadLoop","detail":"{readStateIndex:15629; appliedIndex:15627; }","duration":"385.565263ms","start":"2026-10-20T17:11:31.088193Z","end":"2026-10-20T17:11:31.473758Z","steps":["trace[950641629] 'read index received' (duration: 368.491586ms)","trace[950641629] 'applied index is now lower than readState.Index' (duration: 17.072127ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T17:11:31.474023Z","caller":"traceutil/trace.go:171","msg":"trace[1333078164] transaction","detail":"{read_only:false; response_revision:15104; number_of_response:1; }","duration":"139.408238ms","start":"2026-10-20T17:11:31.3346Z","end":"2026-10-20T17:11:31.474009Z","steps":["trace[1333078164] 'process raft request' (duration: 139.08794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:31.474185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.670201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-20T17:11:31.474135Z","caller":"traceutil/trace.go:171","msg":"trace[1627214448] transaction","detail":"{read_only:false; response_revision:15103; number_of_response:1; }","duration":"419.824449ms","start":"2026-10-20T17:11:31.054227Z","end":"2026-10-20T17:11:31.474051Z","steps":["trace[1627214448] 'process raft request' (duration: 419.41111ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:11:31.474237Z","caller":"traceutil/trace.go:171","msg":"trace[956587665] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:15104; }","duration":"114.759363ms","start":"2026-10-20T17:11:31.359464Z","end":"2026-10-20T17:11:31.474223Z","steps":["trace[956587665] 'agreement among raft nodes before linearized reading' (duration: 114.660371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:31.474405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:11:31.054192Z","time spent":"420.065294ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-20T17:11:31.474672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.448762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2fc5q\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-20T17:11:31.474855Z","caller":"traceutil/trace.go:171","msg":"trace[539356156] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2fc5q; range_end:; response_count:1; response_revision:15104; }","duration":"386.707968ms","start":"2026-10-20T17:11:31.088119Z","end":"2026-10-20T17:11:31.474827Z","steps":["trace[539356156] 'agreement among raft nodes before linearized reading' (duration: 386.217507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:31.474986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:11:31.088072Z","time spent":"386.897912ms","remote":"127.0.0.1:36022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-2fc5q\" "} {"level":"info","ts":"2026-10-20T17:11:42.367891Z","caller":"traceutil/trace.go:171","msg":"trace[984665539] transaction","detail":"{read_only:false; response_revision:15278; number_of_response:1; }","duration":"137.709581ms","start":"2026-10-20T17:11:42.230153Z","end":"2026-10-20T17:11:42.367862Z","steps":["trace[984665539] 'process raft request' (duration: 137.545447ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:11:42.622443Z","caller":"traceutil/trace.go:171","msg":"trace[1314295306] transaction","detail":"{read_only:false; response_revision:15279; number_of_response:1; }","duration":"391.483124ms","start":"2026-10-20T17:11:42.230925Z","end":"2026-10-20T17:11:42.622408Z","steps":["trace[1314295306] 'process raft request' (duration: 296.752612ms)","trace[1314295306] 'compare' (duration: 94.423245ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T17:11:42.622661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:11:42.230906Z","time spent":"391.653547ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T17:11:42.62278Z","caller":"traceutil/trace.go:171","msg":"trace[1036693340] transaction","detail":"{read_only:false; response_revision:15280; number_of_response:1; }","duration":"391.726039ms","start":"2026-10-20T17:11:42.231018Z","end":"2026-10-20T17:11:42.622744Z","steps":["trace[1036693340] 'process raft request' (duration: 391.33301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:42.622999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:11:42.23101Z","time spent":"391.873432ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T17:11:42.62309Z","caller":"traceutil/trace.go:171","msg":"trace[719326598] linearizableReadLoop","detail":"{readStateIndex:15811; appliedIndex:15806; }","duration":"261.258788ms","start":"2026-10-20T17:11:42.361797Z","end":"2026-10-20T17:11:42.623056Z","steps":["trace[719326598] 'read index received' (duration: 5.89425ms)","trace[719326598] 'applied index is now lower than readState.Index' (duration: 255.363238ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T17:11:42.623201Z","caller":"traceutil/trace.go:171","msg":"trace[43372586] transaction","detail":"{read_only:false; response_revision:15281; number_of_response:1; }","duration":"391.904393ms","start":"2026-10-20T17:11:42.231282Z","end":"2026-10-20T17:11:42.623187Z","steps":["trace[43372586] 'process raft request' (duration: 391.381881ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:11:42.623303Z","caller":"traceutil/trace.go:171","msg":"trace[420532940] transaction","detail":"{read_only:false; response_revision:15282; number_of_response:1; }","duration":"389.609932ms","start":"2026-10-20T17:11:42.233677Z","end":"2026-10-20T17:11:42.623287Z","steps":["trace[420532940] 'process raft request' (duration: 389.271374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:42.62335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:11:42.231274Z","time spent":"392.039696ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-20T17:11:42.623458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.538029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-rdhjk.18e04c1416d1a8f0\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-10-20T17:11:42.623467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:11:42.233663Z","time spent":"389.672524ms","remote":"127.0.0.1:36022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3712,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T17:11:42.623515Z","caller":"traceutil/trace.go:171","msg":"trace[679525870] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-rdhjk.18e04c1416d1a8f0; range_end:; response_count:1; response_revision:15282; }","duration":"230.664353ms","start":"2026-10-20T17:11:42.392833Z","end":"2026-10-20T17:11:42.623498Z","steps":["trace[679525870] 'agreement among raft nodes before linearized reading' (duration: 230.497329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:42.623525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.729659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T17:11:42.623612Z","caller":"traceutil/trace.go:171","msg":"trace[1259985743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15282; }","duration":"261.862662ms","start":"2026-10-20T17:11:42.361728Z","end":"2026-10-20T17:11:42.623591Z","steps":["trace[1259985743] 'agreement among raft nodes before linearized reading' (duration: 261.730039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:11:42.623699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.247026ms","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:5"} {"level":"info","ts":"2026-10-20T17:11:42.623736Z","caller":"traceutil/trace.go:171","msg":"trace[190702499] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:15282; }","duration":"111.347909ms","start":"2026-10-20T17:11:42.512376Z","end":"2026-10-20T17:11:42.623724Z","steps":["trace[190702499] 'agreement among raft nodes before linearized reading' (duration: 111.286277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:12:51.42416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.132118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-2fxfn\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-10-20T17:12:51.424323Z","caller":"traceutil/trace.go:171","msg":"trace[72685704] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-2fxfn; range_end:; response_count:1; response_revision:16207; }","duration":"128.390433ms","start":"2026-10-20T17:12:51.295899Z","end":"2026-10-20T17:12:51.424289Z","steps":["trace[72685704] 'range keys from in-memory index tree' (duration: 127.821101ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:13:25.428139Z","caller":"traceutil/trace.go:171","msg":"trace[684006725] transaction","detail":"{read_only:false; response_revision:16383; number_of_response:1; }","duration":"158.538949ms","start":"2026-10-20T17:13:25.269563Z","end":"2026-10-20T17:13:25.428102Z","steps":["trace[684006725] 'process raft request' (duration: 157.099597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:13:25.78976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.020099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-20T17:13:25.789861Z","caller":"traceutil/trace.go:171","msg":"trace[286818994] linearizableReadLoop","detail":"{readStateIndex:16947; appliedIndex:16945; }","duration":"518.770389ms","start":"2026-10-20T17:13:25.271073Z","end":"2026-10-20T17:13:25.789843Z","steps":["trace[286818994] 'read index received' (duration: 156.471063ms)","trace[286818994] 'applied index is now lower than readState.Index' (duration: 362.297776ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T17:13:25.790055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.977804ms","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-20T17:13:25.789961Z","caller":"traceutil/trace.go:171","msg":"trace[202083201] transaction","detail":"{read_only:false; response_revision:16384; number_of_response:1; }","duration":"519.66907ms","start":"2026-10-20T17:13:25.270229Z","end":"2026-10-20T17:13:25.789898Z","steps":["trace[202083201] 'process raft request' (duration: 336.421155ms)","trace[202083201] 'compare' (duration: 182.11712ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T17:13:25.790097Z","caller":"traceutil/trace.go:171","msg":"trace[1684644646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16384; }","duration":"519.072386ms","start":"2026-10-20T17:13:25.271012Z","end":"2026-10-20T17:13:25.790084Z","steps":["trace[1684644646] 'agreement among raft nodes before linearized reading' (duration: 518.907062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:13:25.790151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:13:25.271003Z","time spent":"519.139948ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-20T17:13:25.79013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.064768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-20T17:13:25.790134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.48663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T17:13:25.790223Z","caller":"traceutil/trace.go:171","msg":"trace[312286517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16384; }","duration":"428.209301ms","start":"2026-10-20T17:13:25.361988Z","end":"2026-10-20T17:13:25.790197Z","steps":["trace[312286517] 'agreement among raft nodes before linearized reading' (duration: 428.076578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:13:25.790208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.994797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"info","ts":"2026-10-20T17:13:25.790292Z","caller":"traceutil/trace.go:171","msg":"trace[1764594717] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16384; }","duration":"491.090249ms","start":"2026-10-20T17:13:25.299173Z","end":"2026-10-20T17:13:25.790264Z","steps":["trace[1764594717] 'agreement among raft nodes before linearized reading' (duration: 490.884454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:13:25.790294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:13:25.361958Z","time spent":"428.302713ms","remote":"127.0.0.1:35830","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-20T17:13:25.790322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:13:25.299165Z","time spent":"491.15111ms","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-10-20T17:13:25.790299Z","caller":"traceutil/trace.go:171","msg":"trace[2099184350] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16384; }","duration":"492.650473ms","start":"2026-10-20T17:13:25.297592Z","end":"2026-10-20T17:13:25.790243Z","steps":["trace[2099184350] 'agreement among raft nodes before linearized reading' (duration: 492.424658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:13:25.790394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.741225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T17:13:25.790501Z","caller":"traceutil/trace.go:171","msg":"trace[1089000255] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16384; }","duration":"492.886639ms","start":"2026-10-20T17:13:25.297593Z","end":"2026-10-20T17:13:25.790479Z","steps":["trace[1089000255] 'agreement among raft nodes before linearized reading' (duration: 492.664104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:13:25.790559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:13:25.297579Z","time spent":"492.97019ms","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-10-20T17:13:25.790426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:13:25.270207Z","time spent":"520.100669ms","remote":"127.0.0.1:36108","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-20T17:13:25.790837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.166664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T17:13:25.791075Z","caller":"traceutil/trace.go:171","msg":"trace[672943890] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16384; }","duration":"493.38439ms","start":"2026-10-20T17:13:25.29764Z","end":"2026-10-20T17:13:25.791025Z","steps":["trace[672943890] 'agreement among raft nodes before linearized reading' (duration: 493.117464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:13:25.79122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:13:25.297633Z","time spent":"493.573394ms","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-10-20T17:13:25.790575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:13:25.297501Z","time spent":"493.052142ms","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-10-20T17:15:04.965412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14564} {"level":"info","ts":"2026-10-20T17:15:05.053807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14564,"took":"84.069556ms","hash":3466778092,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-20T17:15:05.053891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3466778092,"revision":14564,"compact-revision":12545} {"level":"warn","ts":"2026-10-20T17:19:00.070251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.84571ms","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-20T17:19:00.070401Z","caller":"traceutil/trace.go:171","msg":"trace[383450408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18869; }","duration":"235.070655ms","start":"2026-10-20T17:18:59.835296Z","end":"2026-10-20T17:19:00.070367Z","steps":["trace[383450408] 'range keys from in-memory index tree' (duration: 234.582524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:19:00.070487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.833563ms","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-20T17:19:00.070582Z","caller":"traceutil/trace.go:171","msg":"trace[1155708179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18869; }","duration":"231.959817ms","start":"2026-10-20T17:18:59.838599Z","end":"2026-10-20T17:19:00.070559Z","steps":["trace[1155708179] 'range keys from in-memory index tree' (duration: 231.63757ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:19:07.52414Z","caller":"traceutil/trace.go:171","msg":"trace[107990056] linearizableReadLoop","detail":"{readStateIndex:19554; appliedIndex:19553; }","duration":"135.653433ms","start":"2026-10-20T17:19:07.388461Z","end":"2026-10-20T17:19:07.524115Z","steps":["trace[107990056] 'read index received' (duration: 135.439238ms)","trace[107990056] 'applied index is now lower than readState.Index' (duration: 212.495µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T17:19:07.524264Z","caller":"traceutil/trace.go:171","msg":"trace[1234164952] transaction","detail":"{read_only:false; response_revision:18909; number_of_response:1; }","duration":"190.453492ms","start":"2026-10-20T17:19:07.333796Z","end":"2026-10-20T17:19:07.524249Z","steps":["trace[1234164952] 'process raft request' (duration: 190.113225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:19:07.524525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.055772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12433"} {"level":"info","ts":"2026-10-20T17:19:07.524564Z","caller":"traceutil/trace.go:171","msg":"trace[57349781] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18909; }","duration":"136.143524ms","start":"2026-10-20T17:19:07.388412Z","end":"2026-10-20T17:19:07.524555Z","steps":["trace[57349781] 'agreement among raft nodes before linearized reading' (duration: 135.98153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:19:07.974159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.568849ms","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-20T17:19:07.974305Z","caller":"traceutil/trace.go:171","msg":"trace[130336007] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18913; }","duration":"352.877475ms","start":"2026-10-20T17:19:07.621396Z","end":"2026-10-20T17:19:07.974273Z","steps":["trace[130336007] 'range keys from in-memory index tree' (duration: 352.416665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:19:07.974671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:19:07.621364Z","time spent":"352.978938ms","remote":"127.0.0.1:36290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-20T17:19:07.991916Z","caller":"traceutil/trace.go:171","msg":"trace[210726471] transaction","detail":"{read_only:false; response_revision:18914; number_of_response:1; }","duration":"220.651798ms","start":"2026-10-20T17:19:07.771232Z","end":"2026-10-20T17:19:07.991884Z","steps":["trace[210726471] 'process raft request' (duration: 220.424583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:19:08.497383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.358734ms","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-20T17:19:08.497586Z","caller":"traceutil/trace.go:171","msg":"trace[2022496801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18914; }","duration":"372.653622ms","start":"2026-10-20T17:19:08.124894Z","end":"2026-10-20T17:19:08.497547Z","steps":["trace[2022496801] 'range keys from in-memory index tree' (duration: 371.976966ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:19:08.49758Z","caller":"traceutil/trace.go:171","msg":"trace[1484878716] linearizableReadLoop","detail":"{readStateIndex:19560; appliedIndex:19559; }","duration":"234.528604ms","start":"2026-10-20T17:19:08.263008Z","end":"2026-10-20T17:19:08.497537Z","steps":["trace[1484878716] 'read index received' (duration: 184.317787ms)","trace[1484878716] 'applied index is now lower than readState.Index' (duration: 50.209727ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T17:19:08.497608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.236155ms","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-20T17:19:08.497675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:19:08.124865Z","time spent":"372.796104ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-20T17:19:08.497737Z","caller":"traceutil/trace.go:171","msg":"trace[1380467494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18914; }","duration":"359.437029ms","start":"2026-10-20T17:19:08.138272Z","end":"2026-10-20T17:19:08.497709Z","steps":["trace[1380467494] 'range keys from in-memory index tree' (duration: 358.637252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:19:08.497797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T17:19:08.138251Z","time spent":"359.531452ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-20T17:19:08.497929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.009361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T17:19:08.497975Z","caller":"traceutil/trace.go:171","msg":"trace[312640637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18915; }","duration":"136.090333ms","start":"2026-10-20T17:19:08.361867Z","end":"2026-10-20T17:19:08.497958Z","steps":["trace[312640637] 'agreement among raft nodes before linearized reading' (duration: 136.021191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:19:08.498092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.079716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.239\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-20T17:19:08.498149Z","caller":"traceutil/trace.go:171","msg":"trace[1566902997] range","detail":"{range_begin:/registry/masterleases/162.253.55.239; range_end:; response_count:1; response_revision:18915; }","duration":"235.194679ms","start":"2026-10-20T17:19:08.26294Z","end":"2026-10-20T17:19:08.498134Z","steps":["trace[1566902997] 'agreement among raft nodes before linearized reading' (duration: 235.009335ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:19:08.498351Z","caller":"traceutil/trace.go:171","msg":"trace[479462731] transaction","detail":"{read_only:false; response_revision:18915; number_of_response:1; }","duration":"279.800063ms","start":"2026-10-20T17:19:08.218538Z","end":"2026-10-20T17:19:08.498338Z","steps":["trace[479462731] 'process raft request' (duration: 228.774128ms)","trace[479462731] 'compare' (duration: 49.89682ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T17:19:08.795316Z","caller":"traceutil/trace.go:171","msg":"trace[1393001703] transaction","detail":"{read_only:false; response_revision:18917; number_of_response:1; }","duration":"291.90786ms","start":"2026-10-20T17:19:08.503379Z","end":"2026-10-20T17:19:08.795287Z","steps":["trace[1393001703] 'process raft request' (duration: 291.799867ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:19:08.795362Z","caller":"traceutil/trace.go:171","msg":"trace[1563260403] transaction","detail":"{read_only:false; response_revision:18916; number_of_response:1; }","duration":"293.857293ms","start":"2026-10-20T17:19:08.501454Z","end":"2026-10-20T17:19:08.795311Z","steps":["trace[1563260403] 'process raft request' (duration: 293.487895ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:19:08.795502Z","caller":"traceutil/trace.go:171","msg":"trace[659695940] linearizableReadLoop","detail":"{readStateIndex:19564; appliedIndex:19560; }","duration":"229.845891ms","start":"2026-10-20T17:19:08.565621Z","end":"2026-10-20T17:19:08.795467Z","steps":["trace[659695940] 'read index received' (duration: 145.430998ms)","trace[659695940] 'applied index is now lower than readState.Index' (duration: 84.413132ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T17:19:08.795682Z","caller":"traceutil/trace.go:171","msg":"trace[2068797839] transaction","detail":"{read_only:false; response_revision:18918; number_of_response:1; }","duration":"292.080614ms","start":"2026-10-20T17:19:08.503548Z","end":"2026-10-20T17:19:08.795629Z","steps":["trace[2068797839] 'process raft request' (duration: 291.695646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T17:19:08.795842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.194178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lq6gv\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-10-20T17:19:08.795902Z","caller":"traceutil/trace.go:171","msg":"trace[1053954098] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lq6gv; range_end:; response_count:1; response_revision:18918; }","duration":"230.300841ms","start":"2026-10-20T17:19:08.565579Z","end":"2026-10-20T17:19:08.79588Z","steps":["trace[1053954098] 'agreement among raft nodes before linearized reading' (duration: 230.042275ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T17:20:04.976462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17023} {"level":"info","ts":"2026-10-20T17:20:05.087471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17023,"took":"106.334956ms","hash":2801013675,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":30584832,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-20T17:20:05.087566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2801013675,"revision":17023,"compact-revision":14564} {"level":"info","ts":"2026-10-20T17:20:15.331553Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"548b318b3528a14c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-20T17:20:15.33925Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-20T17:20:15.339507Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-20T17:25:04.986678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19283} {"level":"info","ts":"2026-10-20T17:25:05.092117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19283,"took":"101.802993ms","hash":4058460522,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":25128960,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-20T17:25:05.092211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4058460522,"revision":19283,"compact-revision":17023}