{"level":"warn","ts":"2026-10-26T01:00:44.396724Z","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-26T01:00:44.396838Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.38: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.38:2380","--initial-cluster=instance=https://162.253.55.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.38: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-26T01:00:44.396919Z","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-26T01:00:44.396936Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.38:2380"]} {"level":"info","ts":"2026-10-26T01:00:44.396958Z","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-26T01:00:44.397849Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"]} {"level":"info","ts":"2026-10-26T01:00:44.397955Z","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.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.38: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-26T01:00:44.401498Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.265439ms"} {"level":"info","ts":"2026-10-26T01:00:44.409606Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30b39d71f9e69e7c","cluster-id":"2afc6404f924db16"} {"level":"info","ts":"2026-10-26T01:00:44.409706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T01:00:44.409734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 0"} {"level":"info","ts":"2026-10-26T01:00:44.409749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30b39d71f9e69e7c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T01:00:44.40976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 1"} {"level":"info","ts":"2026-10-26T01:00:44.409809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"} {"level":"warn","ts":"2026-10-26T01:00:44.420538Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T01:00:44.423521Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T01:00:44.426057Z","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-26T01:00:44.429576Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30b39d71f9e69e7c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T01:00:44.429829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30b39d71f9e69e7c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T01:00:44.430122Z","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-26T01:00:44.430243Z","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-26T01:00:44.430258Z","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-26T01:00:44.432489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"} {"level":"info","ts":"2026-10-26T01:00:44.433917Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","added-peer-id":"30b39d71f9e69e7c","added-peer-peer-urls":["https://162.253.55.38:2380"]} {"level":"info","ts":"2026-10-26T01:00:44.436326Z","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-26T01:00:44.436509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.38:2380"} {"level":"info","ts":"2026-10-26T01:00:44.436543Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.38:2380"} {"level":"info","ts":"2026-10-26T01:00:44.436829Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30b39d71f9e69e7c","initial-advertise-peer-urls":["https://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T01:00:44.436923Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T01:00:45.410314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T01:00:45.41045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T01:00:45.410493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgPreVoteResp from 30b39d71f9e69e7c at term 1"} {"level":"info","ts":"2026-10-26T01:00:45.410525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became candidate at term 2"} {"level":"info","ts":"2026-10-26T01:00:45.410551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgVoteResp from 30b39d71f9e69e7c at term 2"} {"level":"info","ts":"2026-10-26T01:00:45.410573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became leader at term 2"} {"level":"info","ts":"2026-10-26T01:00:45.410599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30b39d71f9e69e7c elected leader 30b39d71f9e69e7c at term 2"} {"level":"info","ts":"2026-10-26T01:00:45.41747Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:00:45.418727Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T01:00:45.418723Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30b39d71f9e69e7c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.38:2379]}","request-path":"/0/members/30b39d71f9e69e7c/attributes","cluster-id":"2afc6404f924db16","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T01:00:45.418856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T01:00:45.419269Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T01:00:45.419332Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T01:00:45.422839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T01:00:45.422901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.38:2379"} {"level":"info","ts":"2026-10-26T01:00:45.425585Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:00:45.425898Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:00:45.425957Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:01:03.413861Z","caller":"traceutil/trace.go:171","msg":"trace[1965575825] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"184.756498ms","start":"2026-10-26T01:01:03.229077Z","end":"2026-10-26T01:01:03.413833Z","steps":["trace[1965575825] 'read index received' (duration: 184.433899ms)","trace[1965575825] 'applied index is now lower than readState.Index' (duration: 321.459µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:01:03.413956Z","caller":"traceutil/trace.go:171","msg":"trace[249709941] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"269.895088ms","start":"2026-10-26T01:01:03.144015Z","end":"2026-10-26T01:01:03.41391Z","steps":["trace[249709941] 'process raft request' (duration: 269.562359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:03.414016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.918993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T01:01:03.414083Z","caller":"traceutil/trace.go:171","msg":"trace[1001681930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"185.067346ms","start":"2026-10-26T01:01:03.229004Z","end":"2026-10-26T01:01:03.414071Z","steps":["trace[1001681930] 'agreement among raft nodes before linearized reading' (duration: 184.942313ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:15.998077Z","caller":"traceutil/trace.go:171","msg":"trace[16571072] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"101.448148ms","start":"2026-10-26T01:01:15.896568Z","end":"2026-10-26T01:01:15.998016Z","steps":["trace[16571072] 'process raft request' (duration: 32.340679ms)","trace[16571072] 'compare' (duration: 68.868753ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:01:15.998205Z","caller":"traceutil/trace.go:171","msg":"trace[80858490] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"101.228722ms","start":"2026-10-26T01:01:15.896854Z","end":"2026-10-26T01:01:15.998082Z","steps":["trace[80858490] 'read index received' (duration: 32.067811ms)","trace[80858490] 'applied index is now lower than readState.Index' (duration: 69.157761ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:01:15.998344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.471909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-gcz5p\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-10-26T01:01:15.99843Z","caller":"traceutil/trace.go:171","msg":"trace[291580324] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-gcz5p; range_end:; response_count:1; response_revision:422; }","duration":"101.602192ms","start":"2026-10-26T01:01:15.896808Z","end":"2026-10-26T01:01:15.99841Z","steps":["trace[291580324] 'agreement among raft nodes before linearized reading' (duration: 101.443418ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:16.181099Z","caller":"traceutil/trace.go:171","msg":"trace[552800511] transaction","detail":"{read_only:false; number_of_response:1; response_revision:430; }","duration":"128.149492ms","start":"2026-10-26T01:01:16.052926Z","end":"2026-10-26T01:01:16.181075Z","steps":["trace[552800511] 'process raft request' (duration: 128.0987ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:16.187446Z","caller":"traceutil/trace.go:171","msg":"trace[1632089394] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"134.882067ms","start":"2026-10-26T01:01:16.046445Z","end":"2026-10-26T01:01:16.181327Z","steps":["trace[1632089394] 'process raft request' (duration: 134.471585ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:16.187745Z","caller":"traceutil/trace.go:171","msg":"trace[1942179678] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"136.722368ms","start":"2026-10-26T01:01:16.050972Z","end":"2026-10-26T01:01:16.187694Z","steps":["trace[1942179678] 'process raft request' (duration: 129.998653ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:16.188172Z","caller":"traceutil/trace.go:171","msg":"trace[290259854] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"142.322682ms","start":"2026-10-26T01:01:16.045825Z","end":"2026-10-26T01:01:16.188148Z","steps":["trace[290259854] 'process raft request' (duration: 129.820848ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:31.070207Z","caller":"traceutil/trace.go:171","msg":"trace[1320184696] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"118.518857ms","start":"2026-10-26T01:01:30.951661Z","end":"2026-10-26T01:01:31.07018Z","steps":["trace[1320184696] 'process raft request' (duration: 116.262025ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:31.075998Z","caller":"traceutil/trace.go:171","msg":"trace[390561651] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"119.904905ms","start":"2026-10-26T01:01:30.956053Z","end":"2026-10-26T01:01:31.075958Z","steps":["trace[390561651] 'process raft request' (duration: 119.574776ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:31.269375Z","caller":"traceutil/trace.go:171","msg":"trace[1505279353] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"107.428678ms","start":"2026-10-26T01:01:31.161927Z","end":"2026-10-26T01:01:31.269355Z","steps":["trace[1505279353] 'process raft request' (duration: 103.120408ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:31.28191Z","caller":"traceutil/trace.go:171","msg":"trace[1386043409] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"116.613924ms","start":"2026-10-26T01:01:31.165279Z","end":"2026-10-26T01:01:31.281893Z","steps":["trace[1386043409] 'process raft request' (duration: 116.47557ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:37.664339Z","caller":"traceutil/trace.go:171","msg":"trace[1761409065] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"127.298441ms","start":"2026-10-26T01:01:37.536999Z","end":"2026-10-26T01:01:37.664297Z","steps":["trace[1761409065] 'process raft request' (duration: 93.061369ms)","trace[1761409065] 'compare' (duration: 33.986945ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:01:38.09533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.693212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:01:38.095438Z","caller":"traceutil/trace.go:171","msg":"trace[67876849] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"180.08843ms","start":"2026-10-26T01:01:37.915332Z","end":"2026-10-26T01:01:38.095421Z","steps":["trace[67876849] 'read index received' (duration: 43.132µs)","trace[67876849] 'applied index is now lower than readState.Index' (duration: 180.044038ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:01:38.095587Z","caller":"traceutil/trace.go:171","msg":"trace[1944736331] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"380.500165ms","start":"2026-10-26T01:01:37.715046Z","end":"2026-10-26T01:01:38.095546Z","steps":["trace[1944736331] 'process raft request' (duration: 87.070442ms)","trace[1944736331] 'compare' (duration: 292.589899ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:01:38.095762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:37.715016Z","time spent":"380.644659ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:01:38.095937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.154782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n6td4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-26T01:01:38.096012Z","caller":"traceutil/trace.go:171","msg":"trace[1253282143] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n6td4; range_end:; response_count:1; response_revision:618; }","duration":"157.264515ms","start":"2026-10-26T01:01:37.938728Z","end":"2026-10-26T01:01:38.095993Z","steps":["trace[1253282143] 'agreement among raft nodes before linearized reading' (duration: 157.004808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:38.095975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.627275ms","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-26T01:01:38.096315Z","caller":"traceutil/trace.go:171","msg":"trace[1551391115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"180.993916ms","start":"2026-10-26T01:01:37.915264Z","end":"2026-10-26T01:01:38.096258Z","steps":["trace[1551391115] 'agreement among raft nodes before linearized reading' (duration: 180.282146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:38.671315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11420180282097794622,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T01:01:38.724402Z","caller":"traceutil/trace.go:171","msg":"trace[321126244] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"606.398209ms","start":"2026-10-26T01:01:38.117975Z","end":"2026-10-26T01:01:38.724373Z","steps":["trace[321126244] 'process raft request' (duration: 565.509261ms)","trace[321126244] 'compare' (duration: 40.627211ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:01:38.724552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:38.117946Z","time spent":"606.529803ms","remote":"127.0.0.1:58214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:01:38.752934Z","caller":"traceutil/trace.go:171","msg":"trace[1867038518] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"594.959201ms","start":"2026-10-26T01:01:38.157949Z","end":"2026-10-26T01:01:38.752908Z","steps":["trace[1867038518] 'process raft request' (duration: 594.350644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:38.753111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:38.157916Z","time spent":"595.101415ms","remote":"127.0.0.1:58128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:01:38.75313Z","caller":"traceutil/trace.go:171","msg":"trace[1407721787] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"582.625929ms","start":"2026-10-26T01:01:38.170485Z","end":"2026-10-26T01:01:38.75311Z","steps":["trace[1407721787] 'read index received' (duration: 513.047733ms)","trace[1407721787] 'applied index is now lower than readState.Index' (duration: 69.576986ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:01:38.753227Z","caller":"traceutil/trace.go:171","msg":"trace[66251811] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"441.702738ms","start":"2026-10-26T01:01:38.311514Z","end":"2026-10-26T01:01:38.753217Z","steps":["trace[66251811] 'process raft request' (duration: 441.322377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:38.753324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:38.311476Z","time spent":"441.79467ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:01:38.753538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.03904ms","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-26T01:01:38.753625Z","caller":"traceutil/trace.go:171","msg":"trace[1311144389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"583.161103ms","start":"2026-10-26T01:01:38.170438Z","end":"2026-10-26T01:01:38.753599Z","steps":["trace[1311144389] 'agreement among raft nodes before linearized reading' (duration: 582.911306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:38.753706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:38.170415Z","time spent":"583.277317ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-26T01:01:40.213329Z","caller":"traceutil/trace.go:171","msg":"trace[1197317681] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"101.594316ms","start":"2026-10-26T01:01:40.111699Z","end":"2026-10-26T01:01:40.213293Z","steps":["trace[1197317681] 'read index received' (duration: 101.431511ms)","trace[1197317681] 'applied index is now lower than readState.Index' (duration: 161.275µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:01:40.213439Z","caller":"traceutil/trace.go:171","msg":"trace[176274549] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"108.685174ms","start":"2026-10-26T01:01:40.104708Z","end":"2026-10-26T01:01:40.213393Z","steps":["trace[176274549] 'process raft request' (duration: 108.399326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:40.213526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.824912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-26T01:01:40.213593Z","caller":"traceutil/trace.go:171","msg":"trace[1186174897] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:643; }","duration":"101.941486ms","start":"2026-10-26T01:01:40.111638Z","end":"2026-10-26T01:01:40.213579Z","steps":["trace[1186174897] 'agreement among raft nodes before linearized reading' (duration: 101.785251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:02:08.700088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.583806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:02:08.700202Z","caller":"traceutil/trace.go:171","msg":"trace[1374906419] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"154.55131ms","start":"2026-10-26T01:02:08.545633Z","end":"2026-10-26T01:02:08.700185Z","steps":["trace[1374906419] 'process raft request' (duration: 43.795199ms)","trace[1374906419] 'compare' (duration: 110.471223ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:02:08.704395Z","caller":"traceutil/trace.go:171","msg":"trace[695767274] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"155.856966ms","start":"2026-10-26T01:02:08.548514Z","end":"2026-10-26T01:02:08.704371Z","steps":["trace[695767274] 'process raft request' (duration: 155.740383ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:02:24.14528Z","caller":"traceutil/trace.go:171","msg":"trace[2017725107] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"120.414415ms","start":"2026-10-26T01:02:24.024837Z","end":"2026-10-26T01:02:24.145251Z","steps":["trace[2017725107] 'process raft request' (duration: 120.238ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:13.649387Z","caller":"traceutil/trace.go:171","msg":"trace[343923204] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"124.002824ms","start":"2026-10-26T01:03:13.525321Z","end":"2026-10-26T01:03:13.649323Z","steps":["trace[343923204] 'process raft request' (duration: 73.181796ms)","trace[343923204] 'compare' (duration: 50.576031ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:03:13.649399Z","caller":"traceutil/trace.go:171","msg":"trace[523425992] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"123.487009ms","start":"2026-10-26T01:03:13.525878Z","end":"2026-10-26T01:03:13.649365Z","steps":["trace[523425992] 'process raft request' (duration: 123.333245ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:13.649631Z","caller":"traceutil/trace.go:171","msg":"trace[301610042] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"123.624233ms","start":"2026-10-26T01:03:13.52599Z","end":"2026-10-26T01:03:13.649614Z","steps":["trace[301610042] 'process raft request' (duration: 123.300714ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:13.649839Z","caller":"traceutil/trace.go:171","msg":"trace[1001151341] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"118.950354ms","start":"2026-10-26T01:03:13.530878Z","end":"2026-10-26T01:03:13.649828Z","steps":["trace[1001151341] 'process raft request' (duration: 118.631715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:14.092516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.991914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:03:14.092729Z","caller":"traceutil/trace.go:171","msg":"trace[1246421422] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1937; }","duration":"427.810006ms","start":"2026-10-26T01:03:13.664903Z","end":"2026-10-26T01:03:14.092713Z","steps":["trace[1246421422] 'read index received' (duration: 312.600626ms)","trace[1246421422] 'applied index is now lower than readState.Index' (duration: 115.20832ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:03:14.092932Z","caller":"traceutil/trace.go:171","msg":"trace[1575297241] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"514.196798ms","start":"2026-10-26T01:03:13.578725Z","end":"2026-10-26T01:03:14.092921Z","steps":["trace[1575297241] 'process raft request' (duration: 513.892159ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:14.09294Z","caller":"traceutil/trace.go:171","msg":"trace[1531748967] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"516.328806ms","start":"2026-10-26T01:03:13.576583Z","end":"2026-10-26T01:03:14.092912Z","steps":["trace[1531748967] 'process raft request' (duration: 400.841309ms)","trace[1531748967] 'compare' (duration: 114.639424ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:03:14.093076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:13.576558Z","time spent":"516.43986ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:03:14.093464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:13.578708Z","time spent":"514.259289ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:03:14.093565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.643779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:03:14.093663Z","caller":"traceutil/trace.go:171","msg":"trace[1160687981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1867; }","duration":"428.782982ms","start":"2026-10-26T01:03:13.664853Z","end":"2026-10-26T01:03:14.093636Z","steps":["trace[1160687981] 'agreement among raft nodes before linearized reading' (duration: 428.625198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:14.093714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:13.664833Z","time spent":"428.868205ms","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T01:03:14.10696Z","caller":"traceutil/trace.go:171","msg":"trace[526958039] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"317.77901ms","start":"2026-10-26T01:03:13.789156Z","end":"2026-10-26T01:03:14.106935Z","steps":["trace[526958039] 'process raft request' (duration: 317.652386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:14.107006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.353024ms","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-26T01:03:14.107054Z","caller":"traceutil/trace.go:171","msg":"trace[216005526] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"132.436937ms","start":"2026-10-26T01:03:13.974602Z","end":"2026-10-26T01:03:14.107039Z","steps":["trace[216005526] 'agreement among raft nodes before linearized reading' (duration: 132.301873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:14.107109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:13.789117Z","time spent":"317.895763ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:03:14.107289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.937092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7935260d-bb09-452f-ab1b-486aa2844dfd\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-10-26T01:03:14.107367Z","caller":"traceutil/trace.go:171","msg":"trace[2079016314] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7935260d-bb09-452f-ab1b-486aa2844dfd; range_end:; response_count:1; response_revision:1868; }","duration":"228.059965ms","start":"2026-10-26T01:03:13.879288Z","end":"2026-10-26T01:03:14.107348Z","steps":["trace[2079016314] 'agreement among raft nodes before linearized reading' (duration: 227.690185ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:27.070315Z","caller":"traceutil/trace.go:171","msg":"trace[845419830] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"149.219122ms","start":"2026-10-26T01:03:26.921069Z","end":"2026-10-26T01:03:27.070288Z","steps":["trace[845419830] 'process raft request' (duration: 149.036387ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:27.071012Z","caller":"traceutil/trace.go:171","msg":"trace[686633822] linearizableReadLoop","detail":"{readStateIndex:2046; appliedIndex:2044; }","duration":"144.286236ms","start":"2026-10-26T01:03:26.926709Z","end":"2026-10-26T01:03:27.070995Z","steps":["trace[686633822] 'read index received' (duration: 143.386581ms)","trace[686633822] 'applied index is now lower than readState.Index' (duration: 898.864µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:03:27.071051Z","caller":"traceutil/trace.go:171","msg":"trace[763946854] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"148.050929ms","start":"2026-10-26T01:03:26.922975Z","end":"2026-10-26T01:03:27.071026Z","steps":["trace[763946854] 'process raft request' (duration: 147.938736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:27.071176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.44216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T01:03:27.071215Z","caller":"traceutil/trace.go:171","msg":"trace[1949701235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1971; }","duration":"144.506561ms","start":"2026-10-26T01:03:26.926698Z","end":"2026-10-26T01:03:27.071204Z","steps":["trace[1949701235] 'agreement among raft nodes before linearized reading' (duration: 144.365247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:03.702818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.526147ms","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-26T01:04:03.702982Z","caller":"traceutil/trace.go:171","msg":"trace[1132887330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2184; }","duration":"125.776314ms","start":"2026-10-26T01:04:03.577187Z","end":"2026-10-26T01:04:03.702963Z","steps":["trace[1132887330] 'range keys from in-memory index tree' (duration: 125.208169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:04.428025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.145471ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-26T01:10:13.791149Z","caller":"traceutil/trace.go:171","msg":"trace[1215491106] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"103.06988ms","start":"2026-10-26T01:10:13.688057Z","end":"2026-10-26T01:10:13.791127Z","steps":["trace[1215491106] 'process raft request' (duration: 102.979168ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:13.791176Z","caller":"traceutil/trace.go:171","msg":"trace[1288341761] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"104.997463ms","start":"2026-10-26T01:10:13.686139Z","end":"2026-10-26T01:10:13.791136Z","steps":["trace[1288341761] 'process raft request' (duration: 84.508281ms)","trace[1288341761] 'compare' (duration: 20.245056ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:10:29.055422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.138259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:10:29.055553Z","caller":"traceutil/trace.go:171","msg":"trace[1703821975] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"269.341355ms","start":"2026-10-26T01:10:28.786192Z","end":"2026-10-26T01:10:29.055533Z","steps":["trace[1703821975] 'process raft request' (duration: 38.005412ms)","trace[1703821975] 'compare' (duration: 230.901731ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:10:29.06047Z","caller":"traceutil/trace.go:171","msg":"trace[469449942] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"273.930074ms","start":"2026-10-26T01:10:28.786517Z","end":"2026-10-26T01:10:29.060447Z","steps":["trace[469449942] 'process raft request' (duration: 273.745379ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:29.060742Z","caller":"traceutil/trace.go:171","msg":"trace[549682555] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"273.799331ms","start":"2026-10-26T01:10:28.786896Z","end":"2026-10-26T01:10:29.060695Z","steps":["trace[549682555] 'process raft request' (duration: 273.498402ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:29.060974Z","caller":"traceutil/trace.go:171","msg":"trace[798519592] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"273.949124ms","start":"2026-10-26T01:10:28.787012Z","end":"2026-10-26T01:10:29.060961Z","steps":["trace[798519592] 'process raft request' (duration: 273.591644ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:29.061084Z","caller":"traceutil/trace.go:171","msg":"trace[2001740236] linearizableReadLoop","detail":"{readStateIndex:5732; appliedIndex:5728; }","duration":"225.960314ms","start":"2026-10-26T01:10:28.835089Z","end":"2026-10-26T01:10:29.06105Z","steps":["trace[2001740236] 'read index received' (duration: 225.146781ms)","trace[2001740236] 'applied index is now lower than readState.Index' (duration: 812.143µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:10:29.061237Z","caller":"traceutil/trace.go:171","msg":"trace[134566381] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"265.18542ms","start":"2026-10-26T01:10:28.796023Z","end":"2026-10-26T01:10:29.061208Z","steps":["trace[134566381] 'process raft request' (duration: 264.872861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:29.061442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.329465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-26T01:10:29.061501Z","caller":"traceutil/trace.go:171","msg":"trace[1209849233] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5524; }","duration":"226.449558ms","start":"2026-10-26T01:10:28.835033Z","end":"2026-10-26T01:10:29.061483Z","steps":["trace[1209849233] 'agreement among raft nodes before linearized reading' (duration: 226.209681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:29.061552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.367854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-26T01:10:29.061601Z","caller":"traceutil/trace.go:171","msg":"trace[1720842251] range","detail":"{range_begin:/registry/masterleases/162.253.55.38; range_end:; response_count:1; response_revision:5524; }","duration":"115.467567ms","start":"2026-10-26T01:10:28.946121Z","end":"2026-10-26T01:10:29.061589Z","steps":["trace[1720842251] 'agreement among raft nodes before linearized reading' (duration: 115.282362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:37.244378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.482387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T01:10:37.2444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.897631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b6d85e4b-9832-4957-80cd-a5bf23a2e750\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-26T01:10:37.24448Z","caller":"traceutil/trace.go:171","msg":"trace[75021591] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5687; }","duration":"154.672802ms","start":"2026-10-26T01:10:37.089786Z","end":"2026-10-26T01:10:37.244459Z","steps":["trace[75021591] 'count revisions from in-memory index tree' (duration: 154.287701ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:37.24449Z","caller":"traceutil/trace.go:171","msg":"trace[38487426] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b6d85e4b-9832-4957-80cd-a5bf23a2e750; range_end:; response_count:1; response_revision:5687; }","duration":"110.048205ms","start":"2026-10-26T01:10:37.134421Z","end":"2026-10-26T01:10:37.24447Z","steps":["trace[38487426] 'range keys from in-memory index tree' (duration: 109.710035ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:37.534541Z","caller":"traceutil/trace.go:171","msg":"trace[1621842940] linearizableReadLoop","detail":"{readStateIndex:5902; appliedIndex:5901; }","duration":"191.11425ms","start":"2026-10-26T01:10:37.343402Z","end":"2026-10-26T01:10:37.534516Z","steps":["trace[1621842940] 'read index received' (duration: 145.090474ms)","trace[1621842940] 'applied index is now lower than readState.Index' (duration: 46.022536ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:10:37.534579Z","caller":"traceutil/trace.go:171","msg":"trace[1767903775] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"195.980996ms","start":"2026-10-26T01:10:37.338563Z","end":"2026-10-26T01:10:37.534544Z","steps":["trace[1767903775] 'process raft request' (duration: 149.881338ms)","trace[1767903775] 'compare' (duration: 45.757108ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:10:37.534795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.207798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T01:10:37.534784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.354087ms","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-26T01:10:37.534843Z","caller":"traceutil/trace.go:171","msg":"trace[1545945855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5689; }","duration":"174.28818ms","start":"2026-10-26T01:10:37.360544Z","end":"2026-10-26T01:10:37.534833Z","steps":["trace[1545945855] 'agreement among raft nodes before linearized reading' (duration: 174.118845ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:37.534863Z","caller":"traceutil/trace.go:171","msg":"trace[1871856952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5689; }","duration":"191.45508ms","start":"2026-10-26T01:10:37.343388Z","end":"2026-10-26T01:10:37.534843Z","steps":["trace[1871856952] 'agreement among raft nodes before linearized reading' (duration: 191.224003ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:37.808857Z","caller":"traceutil/trace.go:171","msg":"trace[1134564952] linearizableReadLoop","detail":"{readStateIndex:5905; appliedIndex:5903; }","duration":"122.909874ms","start":"2026-10-26T01:10:37.685922Z","end":"2026-10-26T01:10:37.808832Z","steps":["trace[1134564952] 'read index received' (duration: 94.219382ms)","trace[1134564952] 'applied index is now lower than readState.Index' (duration: 28.689502ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:10:37.809089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.153531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z5v22\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-26T01:10:37.809148Z","caller":"traceutil/trace.go:171","msg":"trace[2017203404] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z5v22; range_end:; response_count:1; response_revision:5692; }","duration":"123.254283ms","start":"2026-10-26T01:10:37.685857Z","end":"2026-10-26T01:10:37.809112Z","steps":["trace[2017203404] 'agreement among raft nodes before linearized reading' (duration: 123.079319ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:37.809148Z","caller":"traceutil/trace.go:171","msg":"trace[1881288876] transaction","detail":"{read_only:false; response_revision:5691; number_of_response:1; }","duration":"267.871564ms","start":"2026-10-26T01:10:37.541203Z","end":"2026-10-26T01:10:37.809075Z","steps":["trace[1881288876] 'process raft request' (duration: 238.898195ms)","trace[1881288876] 'compare' (duration: 28.207958ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:10:37.809228Z","caller":"traceutil/trace.go:171","msg":"trace[605392200] transaction","detail":"{read_only:false; response_revision:5692; number_of_response:1; }","duration":"219.462882ms","start":"2026-10-26T01:10:37.589735Z","end":"2026-10-26T01:10:37.809198Z","steps":["trace[605392200] 'process raft request' (duration: 218.967168ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:45.457173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2929} {"level":"info","ts":"2026-10-26T01:10:45.536195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2929,"took":"77.625659ms","hash":2555450147,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":32083968,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T01:10:45.536264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2555450147,"revision":2929,"compact-revision":-1} {"level":"info","ts":"2026-10-26T01:15:45.46407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5820} {"level":"info","ts":"2026-10-26T01:15:45.561364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5820,"took":"95.609945ms","hash":2979924753,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":26238976,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T01:15:45.561452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2979924753,"revision":5820,"compact-revision":2929} {"level":"info","ts":"2026-10-26T01:16:27.416397Z","caller":"traceutil/trace.go:171","msg":"trace[1922528799] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"112.039416ms","start":"2026-10-26T01:16:27.304335Z","end":"2026-10-26T01:16:27.416374Z","steps":["trace[1922528799] 'process raft request' (duration: 111.894482ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:30.913052Z","caller":"traceutil/trace.go:171","msg":"trace[288220530] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"176.444954ms","start":"2026-10-26T01:16:30.73656Z","end":"2026-10-26T01:16:30.913005Z","steps":["trace[288220530] 'process raft request' (duration: 176.227948ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:33.033378Z","caller":"traceutil/trace.go:171","msg":"trace[286092842] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"113.446765ms","start":"2026-10-26T01:16:32.919901Z","end":"2026-10-26T01:16:33.033348Z","steps":["trace[286092842] 'process raft request' (duration: 112.90189ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:39.657302Z","caller":"traceutil/trace.go:171","msg":"trace[144997165] transaction","detail":"{read_only:false; response_revision:8374; number_of_response:1; }","duration":"146.941525ms","start":"2026-10-26T01:16:39.510334Z","end":"2026-10-26T01:16:39.657276Z","steps":["trace[144997165] 'process raft request' (duration: 115.873282ms)","trace[144997165] 'compare' (duration: 30.625941ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:40.037274Z","caller":"traceutil/trace.go:171","msg":"trace[244618232] linearizableReadLoop","detail":"{readStateIndex:8679; appliedIndex:8678; }","duration":"113.561038ms","start":"2026-10-26T01:16:39.92369Z","end":"2026-10-26T01:16:40.037251Z","steps":["trace[244618232] 'read index received' (duration: 113.29144ms)","trace[244618232] 'applied index is now lower than readState.Index' (duration: 268.668µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:16:40.037512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.809714ms","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-26T01:16:40.037545Z","caller":"traceutil/trace.go:171","msg":"trace[1643778346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8379; }","duration":"113.889587ms","start":"2026-10-26T01:16:39.923645Z","end":"2026-10-26T01:16:40.037535Z","steps":["trace[1643778346] 'agreement among raft nodes before linearized reading' (duration: 113.700191ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:40.03782Z","caller":"traceutil/trace.go:171","msg":"trace[13915845] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"164.146826ms","start":"2026-10-26T01:16:39.87366Z","end":"2026-10-26T01:16:40.037807Z","steps":["trace[13915845] 'process raft request' (duration: 163.402946ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:45.703657Z","caller":"traceutil/trace.go:171","msg":"trace[1364792726] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"161.519594ms","start":"2026-10-26T01:16:45.542102Z","end":"2026-10-26T01:16:45.703622Z","steps":["trace[1364792726] 'process raft request' (duration: 161.203226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:46.092025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.813811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:16:46.092209Z","caller":"traceutil/trace.go:171","msg":"trace[1085682456] linearizableReadLoop","detail":"{readStateIndex:8712; appliedIndex:8710; }","duration":"275.616166ms","start":"2026-10-26T01:16:45.816576Z","end":"2026-10-26T01:16:46.092192Z","steps":["trace[1085682456] 'read index received' (duration: 42.525167ms)","trace[1085682456] 'applied index is now lower than readState.Index' (duration: 233.089869ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:46.092307Z","caller":"traceutil/trace.go:171","msg":"trace[328029563] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"309.954189ms","start":"2026-10-26T01:16:45.782341Z","end":"2026-10-26T01:16:46.092295Z","steps":["trace[328029563] 'process raft request' (duration: 309.791824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:46.092373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:45.782317Z","time spent":"310.024091ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:16:46.092524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.265299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-26T01:16:46.092272Z","caller":"traceutil/trace.go:171","msg":"trace[934629104] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"390.915531ms","start":"2026-10-26T01:16:45.701299Z","end":"2026-10-26T01:16:46.092214Z","steps":["trace[934629104] 'process raft request' (duration: 157.794102ms)","trace[934629104] 'compare' (duration: 232.637066ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:16:46.092541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.161619ms","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-26T01:16:46.092601Z","caller":"traceutil/trace.go:171","msg":"trace[627289282] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8411; }","duration":"164.371042ms","start":"2026-10-26T01:16:45.928213Z","end":"2026-10-26T01:16:46.092584Z","steps":["trace[627289282] 'agreement among raft nodes before linearized reading' (duration: 164.176446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:46.092588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.458073ms","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-26T01:16:46.092642Z","caller":"traceutil/trace.go:171","msg":"trace[1345918335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8411; }","duration":"265.312334ms","start":"2026-10-26T01:16:45.827312Z","end":"2026-10-26T01:16:46.092624Z","steps":["trace[1345918335] 'agreement among raft nodes before linearized reading' (duration: 265.042966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:46.092527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.938715ms","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-26T01:16:46.092678Z","caller":"traceutil/trace.go:171","msg":"trace[1822275997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8411; }","duration":"243.571887ms","start":"2026-10-26T01:16:45.849092Z","end":"2026-10-26T01:16:46.092664Z","steps":["trace[1822275997] 'agreement among raft nodes before linearized reading' (duration: 243.33333ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:46.092701Z","caller":"traceutil/trace.go:171","msg":"trace[933414655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8411; }","duration":"276.155401ms","start":"2026-10-26T01:16:45.816524Z","end":"2026-10-26T01:16:46.092679Z","steps":["trace[933414655] 'agreement among raft nodes before linearized reading' (duration: 275.861673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:46.09427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:45.701269Z","time spent":"392.870125ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:16:46.36167Z","caller":"traceutil/trace.go:171","msg":"trace[889324060] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"264.4427ms","start":"2026-10-26T01:16:46.097199Z","end":"2026-10-26T01:16:46.361642Z","steps":["trace[889324060] 'process raft request' (duration: 264.215784ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:46.361872Z","caller":"traceutil/trace.go:171","msg":"trace[1826769413] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"264.522622ms","start":"2026-10-26T01:16:46.097328Z","end":"2026-10-26T01:16:46.361851Z","steps":["trace[1826769413] 'process raft request' (duration: 264.264195ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:46.361946Z","caller":"traceutil/trace.go:171","msg":"trace[1807014718] linearizableReadLoop","detail":"{readStateIndex:8716; appliedIndex:8712; }","duration":"213.43593ms","start":"2026-10-26T01:16:46.148473Z","end":"2026-10-26T01:16:46.361909Z","steps":["trace[1807014718] 'read index received' (duration: 22.126527ms)","trace[1807014718] 'applied index is now lower than readState.Index' (duration: 191.308283ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:46.362089Z","caller":"traceutil/trace.go:171","msg":"trace[868151894] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"264.031159ms","start":"2026-10-26T01:16:46.098025Z","end":"2026-10-26T01:16:46.362056Z","steps":["trace[868151894] 'process raft request' (duration: 263.754301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:46.362273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.97656ms","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-26T01:16:46.362413Z","caller":"traceutil/trace.go:171","msg":"trace[1401804377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8415; }","duration":"212.155555ms","start":"2026-10-26T01:16:46.150236Z","end":"2026-10-26T01:16:46.362391Z","steps":["trace[1401804377] 'agreement among raft nodes before linearized reading' (duration: 211.852307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:46.36291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.424297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T01:16:46.362993Z","caller":"traceutil/trace.go:171","msg":"trace[675836641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8415; }","duration":"214.586852ms","start":"2026-10-26T01:16:46.148389Z","end":"2026-10-26T01:16:46.362976Z","steps":["trace[675836641] 'agreement among raft nodes before linearized reading' (duration: 213.668277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:46.603396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.143209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:16:46.603626Z","caller":"traceutil/trace.go:171","msg":"trace[185780033] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"235.551077ms","start":"2026-10-26T01:16:46.368055Z","end":"2026-10-26T01:16:46.603606Z","steps":["trace[185780033] 'process raft request' (duration: 235.460785ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:46.603642Z","caller":"traceutil/trace.go:171","msg":"trace[1070329639] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"236.254247ms","start":"2026-10-26T01:16:46.367355Z","end":"2026-10-26T01:16:46.60361Z","steps":["trace[1070329639] 'process raft request' (duration: 123.800849ms)","trace[1070329639] 'compare' (duration: 112.025396ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:50.292927Z","caller":"traceutil/trace.go:171","msg":"trace[2089138089] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"163.487769ms","start":"2026-10-26T01:16:50.129404Z","end":"2026-10-26T01:16:50.292892Z","steps":["trace[2089138089] 'process raft request' (duration: 133.430693ms)","trace[2089138089] 'compare' (duration: 29.944282ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:51.172948Z","caller":"traceutil/trace.go:171","msg":"trace[2045575235] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"107.752708ms","start":"2026-10-26T01:16:51.065104Z","end":"2026-10-26T01:16:51.172857Z","steps":["trace[2045575235] 'process raft request' (duration: 106.821592ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.173175Z","caller":"traceutil/trace.go:171","msg":"trace[1788665401] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"106.35091ms","start":"2026-10-26T01:16:51.066811Z","end":"2026-10-26T01:16:51.173162Z","steps":["trace[1788665401] 'process raft request' (duration: 105.26914ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.173419Z","caller":"traceutil/trace.go:171","msg":"trace[1292414539] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"101.951269ms","start":"2026-10-26T01:16:51.071457Z","end":"2026-10-26T01:16:51.173408Z","steps":["trace[1292414539] 'process raft request' (duration: 100.734645ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.173543Z","caller":"traceutil/trace.go:171","msg":"trace[1354892277] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"104.281903ms","start":"2026-10-26T01:16:51.069222Z","end":"2026-10-26T01:16:51.173504Z","steps":["trace[1354892277] 'process raft request' (duration: 102.910075ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.173601Z","caller":"traceutil/trace.go:171","msg":"trace[1901767048] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"100.624812ms","start":"2026-10-26T01:16:51.072874Z","end":"2026-10-26T01:16:51.173499Z","steps":["trace[1901767048] 'process raft request' (duration: 99.367718ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.568992Z","caller":"traceutil/trace.go:171","msg":"trace[124177434] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"115.762028ms","start":"2026-10-26T01:16:51.453209Z","end":"2026-10-26T01:16:51.568971Z","steps":["trace[124177434] 'process raft request' (duration: 115.587683ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.569039Z","caller":"traceutil/trace.go:171","msg":"trace[695510689] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"115.814839ms","start":"2026-10-26T01:16:51.453209Z","end":"2026-10-26T01:16:51.569024Z","steps":["trace[695510689] 'process raft request' (duration: 115.690126ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.569162Z","caller":"traceutil/trace.go:171","msg":"trace[452737437] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"115.84377ms","start":"2026-10-26T01:16:51.453309Z","end":"2026-10-26T01:16:51.569153Z","steps":["trace[452737437] 'process raft request' (duration: 115.630084ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.569364Z","caller":"traceutil/trace.go:171","msg":"trace[1384137307] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"115.697106ms","start":"2026-10-26T01:16:51.453655Z","end":"2026-10-26T01:16:51.569352Z","steps":["trace[1384137307] 'process raft request' (duration: 115.511861ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.569312Z","caller":"traceutil/trace.go:171","msg":"trace[1047945184] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"115.915803ms","start":"2026-10-26T01:16:51.453341Z","end":"2026-10-26T01:16:51.569257Z","steps":["trace[1047945184] 'process raft request' (duration: 115.734738ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.569507Z","caller":"traceutil/trace.go:171","msg":"trace[144500764] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"115.973364ms","start":"2026-10-26T01:16:51.453513Z","end":"2026-10-26T01:16:51.569486Z","steps":["trace[144500764] 'process raft request' (duration: 115.609714ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.569711Z","caller":"traceutil/trace.go:171","msg":"trace[151182902] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"115.900802ms","start":"2026-10-26T01:16:51.453783Z","end":"2026-10-26T01:16:51.569684Z","steps":["trace[151182902] 'process raft request' (duration: 115.516421ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.569754Z","caller":"traceutil/trace.go:171","msg":"trace[1343507593] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"115.955043ms","start":"2026-10-26T01:16:51.453783Z","end":"2026-10-26T01:16:51.569738Z","steps":["trace[1343507593] 'process raft request' (duration: 115.47282ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.569806Z","caller":"traceutil/trace.go:171","msg":"trace[1713914832] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"115.006397ms","start":"2026-10-26T01:16:51.45478Z","end":"2026-10-26T01:16:51.569786Z","steps":["trace[1713914832] 'process raft request' (duration: 114.564035ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:51.568934Z","caller":"traceutil/trace.go:171","msg":"trace[291328057] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"115.983884ms","start":"2026-10-26T01:16:51.452926Z","end":"2026-10-26T01:16:51.56891Z","steps":["trace[291328057] 'process raft request' (duration: 115.079999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:57.000236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.806043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:16:57.000336Z","caller":"traceutil/trace.go:171","msg":"trace[2028709243] linearizableReadLoop","detail":"{readStateIndex:8902; appliedIndex:8901; }","duration":"544.636562ms","start":"2026-10-26T01:16:56.455683Z","end":"2026-10-26T01:16:57.00032Z","steps":["trace[2028709243] 'read index received' (duration: 319.597554ms)","trace[2028709243] 'applied index is now lower than readState.Index' (duration: 225.037628ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:16:57.000526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.840118ms","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-26T01:16:57.000559Z","caller":"traceutil/trace.go:171","msg":"trace[1288668308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8597; }","duration":"544.91384ms","start":"2026-10-26T01:16:56.455636Z","end":"2026-10-26T01:16:57.00055Z","steps":["trace[1288668308] 'agreement among raft nodes before linearized reading' (duration: 544.776336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:57.00059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:56.455615Z","time spent":"544.967992ms","remote":"127.0.0.1:58320","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-26T01:16:57.000716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.170765ms","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-26T01:16:57.000806Z","caller":"traceutil/trace.go:171","msg":"trace[52586807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8597; }","duration":"530.268168ms","start":"2026-10-26T01:16:56.470516Z","end":"2026-10-26T01:16:57.000785Z","steps":["trace[52586807] 'agreement among raft nodes before linearized reading' (duration: 529.994601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:57.00086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:56.470486Z","time spent":"530.36092ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T01:16:57.000878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.406574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:16:57.00091Z","caller":"traceutil/trace.go:171","msg":"trace[1740100107] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8597; }","duration":"313.442045ms","start":"2026-10-26T01:16:56.687457Z","end":"2026-10-26T01:16:57.0009Z","steps":["trace[1740100107] 'agreement among raft nodes before linearized reading' (duration: 313.388484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:57.000947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:56.687351Z","time spent":"313.588709ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-10-26T01:16:57.001076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.991302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:16:57.001132Z","caller":"traceutil/trace.go:171","msg":"trace[1694085842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8597; }","duration":"337.082044ms","start":"2026-10-26T01:16:56.664037Z","end":"2026-10-26T01:16:57.001119Z","steps":["trace[1694085842] 'agreement among raft nodes before linearized reading' (duration: 336.999902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:57.001182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:56.664015Z","time spent":"337.153866ms","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T01:16:57.001227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.527035ms","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-26T01:16:57.001263Z","caller":"traceutil/trace.go:171","msg":"trace[1652715088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8597; }","duration":"212.618418ms","start":"2026-10-26T01:16:56.788634Z","end":"2026-10-26T01:16:57.001252Z","steps":["trace[1652715088] 'agreement among raft nodes before linearized reading' (duration: 212.521675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:57.001468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.820018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z5v22\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-26T01:16:57.001526Z","caller":"traceutil/trace.go:171","msg":"trace[2000827641] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z5v22; range_end:; response_count:1; response_revision:8597; }","duration":"524.921091ms","start":"2026-10-26T01:16:56.476591Z","end":"2026-10-26T01:16:57.001512Z","steps":["trace[2000827641] 'agreement among raft nodes before linearized reading' (duration: 524.751786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:57.001571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:56.476583Z","time spent":"524.977502ms","remote":"127.0.0.1:58214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-z5v22\" "} {"level":"warn","ts":"2026-10-26T01:16:57.001612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.204048ms","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-26T01:16:57.00167Z","caller":"traceutil/trace.go:171","msg":"trace[1770584249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8597; }","duration":"529.281531ms","start":"2026-10-26T01:16:56.472374Z","end":"2026-10-26T01:16:57.001656Z","steps":["trace[1770584249] 'agreement among raft nodes before linearized reading' (duration: 529.147717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:57.001714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:56.472364Z","time spent":"529.339552ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T01:16:57.001139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.144628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-26T01:16:57.001777Z","caller":"traceutil/trace.go:171","msg":"trace[413437691] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8597; }","duration":"211.824396ms","start":"2026-10-26T01:16:56.789932Z","end":"2026-10-26T01:16:57.001757Z","steps":["trace[413437691] 'agreement among raft nodes before linearized reading' (duration: 211.143707ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:57.003605Z","caller":"traceutil/trace.go:171","msg":"trace[475198644] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"576.691352ms","start":"2026-10-26T01:16:56.426883Z","end":"2026-10-26T01:16:57.003574Z","steps":["trace[475198644] 'process raft request' (duration: 348.473726ms)","trace[475198644] 'compare' (duration: 224.712219ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:16:57.003913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:56.426858Z","time spent":"576.905208ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:17:08.504822Z","caller":"traceutil/trace.go:171","msg":"trace[705530003] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"113.852216ms","start":"2026-10-26T01:17:08.390935Z","end":"2026-10-26T01:17:08.504787Z","steps":["trace[705530003] 'process raft request' (duration: 107.942054ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:09.2708Z","caller":"traceutil/trace.go:171","msg":"trace[1800357391] linearizableReadLoop","detail":"{readStateIndex:8969; appliedIndex:8967; }","duration":"167.481548ms","start":"2026-10-26T01:17:09.103292Z","end":"2026-10-26T01:17:09.270773Z","steps":["trace[1800357391] 'read index received' (duration: 16.665268ms)","trace[1800357391] 'applied index is now lower than readState.Index' (duration: 150.81508ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:17:09.270813Z","caller":"traceutil/trace.go:171","msg":"trace[21553525] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"176.013772ms","start":"2026-10-26T01:17:09.094771Z","end":"2026-10-26T01:17:09.270784Z","steps":["trace[21553525] 'process raft request' (duration: 155.252352ms)","trace[21553525] 'compare' (duration: 20.615586ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:17:09.271106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.642487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z5v22\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-10-26T01:17:09.271095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.778666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T01:17:09.271144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.174126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-26T01:17:09.27119Z","caller":"traceutil/trace.go:171","msg":"trace[1428148823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8661; }","duration":"167.9004ms","start":"2026-10-26T01:17:09.103264Z","end":"2026-10-26T01:17:09.271165Z","steps":["trace[1428148823] 'agreement among raft nodes before linearized reading' (duration: 167.653713ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:09.271211Z","caller":"traceutil/trace.go:171","msg":"trace[1712758615] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8661; }","duration":"149.280399ms","start":"2026-10-26T01:17:09.12191Z","end":"2026-10-26T01:17:09.271191Z","steps":["trace[1712758615] 'agreement among raft nodes before linearized reading' (duration: 149.068683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:09.271239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.706349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T01:17:09.271263Z","caller":"traceutil/trace.go:171","msg":"trace[1661395842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8661; }","duration":"122.75259ms","start":"2026-10-26T01:17:09.148503Z","end":"2026-10-26T01:17:09.271255Z","steps":["trace[1661395842] 'agreement among raft nodes before linearized reading' (duration: 122.681179ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:09.271161Z","caller":"traceutil/trace.go:171","msg":"trace[555475384] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z5v22; range_end:; response_count:1; response_revision:8661; }","duration":"122.72567ms","start":"2026-10-26T01:17:09.148422Z","end":"2026-10-26T01:17:09.271148Z","steps":["trace[555475384] 'agreement among raft nodes before linearized reading' (duration: 122.572565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:09.271392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.853527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T01:17:09.27142Z","caller":"traceutil/trace.go:171","msg":"trace[1384733470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8661; }","duration":"136.906929ms","start":"2026-10-26T01:17:09.134506Z","end":"2026-10-26T01:17:09.271413Z","steps":["trace[1384733470] 'agreement among raft nodes before linearized reading' (duration: 136.831667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:09.271483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.538542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-10-26T01:17:09.271431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.902044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T01:17:09.271546Z","caller":"traceutil/trace.go:171","msg":"trace[1401817071] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8661; }","duration":"127.650125ms","start":"2026-10-26T01:17:09.143879Z","end":"2026-10-26T01:17:09.271529Z","steps":["trace[1401817071] 'agreement among raft nodes before linearized reading' (duration: 127.47667ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:09.271562Z","caller":"traceutil/trace.go:171","msg":"trace[1497268442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8661; }","duration":"123.069419ms","start":"2026-10-26T01:17:09.148472Z","end":"2026-10-26T01:17:09.271542Z","steps":["trace[1497268442] 'agreement among raft nodes before linearized reading' (duration: 122.73503ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:36.747292Z","caller":"traceutil/trace.go:171","msg":"trace[1917703369] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"390.311537ms","start":"2026-10-26T01:17:36.35696Z","end":"2026-10-26T01:17:36.747271Z","steps":["trace[1917703369] 'process raft request' (duration: 390.187093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:36.747497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:17:36.356935Z","time spent":"390.444251ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:17:36.925612Z","caller":"traceutil/trace.go:171","msg":"trace[1005568805] linearizableReadLoop","detail":"{readStateIndex:9180; appliedIndex:9178; }","duration":"263.609891ms","start":"2026-10-26T01:17:36.661979Z","end":"2026-10-26T01:17:36.925589Z","steps":["trace[1005568805] 'read index received' (duration: 85.397573ms)","trace[1005568805] 'applied index is now lower than readState.Index' (duration: 178.211108ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:17:36.925764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.776136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:17:36.925813Z","caller":"traceutil/trace.go:171","msg":"trace[225580370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8865; }","duration":"263.874218ms","start":"2026-10-26T01:17:36.661924Z","end":"2026-10-26T01:17:36.925798Z","steps":["trace[225580370] 'agreement among raft nodes before linearized reading' (duration: 263.765585ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:36.926163Z","caller":"traceutil/trace.go:171","msg":"trace[106364762] transaction","detail":"{read_only:false; response_revision:8864; number_of_response:1; }","duration":"443.140886ms","start":"2026-10-26T01:17:36.483007Z","end":"2026-10-26T01:17:36.926148Z","steps":["trace[106364762] 'process raft request' (duration: 442.329274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:36.926259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:17:36.482982Z","time spent":"443.215947ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:17:36.926501Z","caller":"traceutil/trace.go:171","msg":"trace[419874953] transaction","detail":"{read_only:false; response_revision:8865; number_of_response:1; }","duration":"320.363517ms","start":"2026-10-26T01:17:36.606122Z","end":"2026-10-26T01:17:36.926486Z","steps":["trace[419874953] 'process raft request' (duration: 319.408131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:36.92658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:17:36.606098Z","time spent":"320.44198ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:17:39.795446Z","caller":"traceutil/trace.go:171","msg":"trace[337575037] transaction","detail":"{read_only:false; response_revision:8881; number_of_response:1; }","duration":"153.224192ms","start":"2026-10-26T01:17:39.642194Z","end":"2026-10-26T01:17:39.795418Z","steps":["trace[337575037] 'process raft request' (duration: 153.021017ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:48.882361Z","caller":"traceutil/trace.go:171","msg":"trace[1092123392] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"162.96104ms","start":"2026-10-26T01:17:48.71938Z","end":"2026-10-26T01:17:48.882341Z","steps":["trace[1092123392] 'process raft request' (duration: 162.400254ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:50.009332Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002432.wal"} {"level":"info","ts":"2026-10-26T01:18:58.334757Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:18:58.347427Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T01:18:58.347575Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T01:19:18.296598Z","caller":"traceutil/trace.go:171","msg":"trace[1646833560] linearizableReadLoop","detail":"{readStateIndex:10129; appliedIndex:10128; }","duration":"160.826732ms","start":"2026-10-26T01:19:18.135733Z","end":"2026-10-26T01:19:18.29656Z","steps":["trace[1646833560] 'read index received' (duration: 13.054318ms)","trace[1646833560] 'applied index is now lower than readState.Index' (duration: 147.770624ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:19:18.296729Z","caller":"traceutil/trace.go:171","msg":"trace[1716585537] transaction","detail":"{read_only:false; response_revision:9784; number_of_response:1; }","duration":"161.87944ms","start":"2026-10-26T01:19:18.134819Z","end":"2026-10-26T01:19:18.296699Z","steps":["trace[1716585537] 'process raft request' (duration: 128.32814ms)","trace[1716585537] 'compare' (duration: 33.142879ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:19:18.296964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.553387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z5v22\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-10-26T01:19:18.29696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.209423ms","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-26T01:19:18.29702Z","caller":"traceutil/trace.go:171","msg":"trace[844651918] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z5v22; range_end:; response_count:1; response_revision:9784; }","duration":"155.67554ms","start":"2026-10-26T01:19:18.141329Z","end":"2026-10-26T01:19:18.297004Z","steps":["trace[844651918] 'agreement among raft nodes before linearized reading' (duration: 155.448644ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:19:18.297031Z","caller":"traceutil/trace.go:171","msg":"trace[2000104480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9784; }","duration":"161.327186ms","start":"2026-10-26T01:19:18.135683Z","end":"2026-10-26T01:19:18.297011Z","steps":["trace[2000104480] 'agreement among raft nodes before linearized reading' (duration: 161.077579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:19:18.29702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.412094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-26T01:19:18.297109Z","caller":"traceutil/trace.go:171","msg":"trace[658364101] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9784; }","duration":"108.604519ms","start":"2026-10-26T01:19:18.188479Z","end":"2026-10-26T01:19:18.297083Z","steps":["trace[658364101] 'agreement among raft nodes before linearized reading' (duration: 108.333772ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:20:45.472099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7995} {"level":"info","ts":"2026-10-26T01:20:45.551494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7995,"took":"77.238746ms","hash":1677610756,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":28078080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T01:20:45.551572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1677610756,"revision":7995,"compact-revision":5820} {"level":"info","ts":"2026-10-26T01:25:45.47946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10389} {"level":"info","ts":"2026-10-26T01:25:45.57166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10389,"took":"89.564773ms","hash":334308908,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":27127808,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T01:25:45.571734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":334308908,"revision":10389,"compact-revision":7995} {"level":"warn","ts":"2026-10-26T01:28:54.645932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.352143ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-26T01:28:54.646202Z","caller":"traceutil/trace.go:171","msg":"trace[440222604] transaction","detail":"{read_only:false; response_revision:14037; number_of_response:1; }","duration":"150.237916ms","start":"2026-10-26T01:28:54.495946Z","end":"2026-10-26T01:28:54.646184Z","steps":["trace[440222604] 'process raft request' (duration: 150.111522ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:29:14.788189Z","caller":"traceutil/trace.go:171","msg":"trace[467661690] linearizableReadLoop","detail":"{readStateIndex:14632; appliedIndex:14631; }","duration":"224.231813ms","start":"2026-10-26T01:29:14.563933Z","end":"2026-10-26T01:29:14.788165Z","steps":["trace[467661690] 'read index received' (duration: 125.143392ms)","trace[467661690] 'applied index is now lower than readState.Index' (duration: 99.087631ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:29:14.788322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.369426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:29:14.788352Z","caller":"traceutil/trace.go:171","msg":"trace[1053217803] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:14144; }","duration":"224.460089ms","start":"2026-10-26T01:29:14.563882Z","end":"2026-10-26T01:29:14.788342Z","steps":["trace[1053217803] 'agreement among raft nodes before linearized reading' (duration: 224.381567ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:29:32.281526Z","caller":"traceutil/trace.go:171","msg":"trace[556104881] transaction","detail":"{read_only:false; response_revision:14250; number_of_response:1; }","duration":"468.209561ms","start":"2026-10-26T01:29:31.81329Z","end":"2026-10-26T01:29:32.281499Z","steps":["trace[556104881] 'process raft request' (duration: 468.054226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:29:32.281731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:29:31.813264Z","time spent":"468.371455ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:29:32.329144Z","caller":"traceutil/trace.go:171","msg":"trace[882149084] transaction","detail":"{read_only:false; response_revision:14251; number_of_response:1; }","duration":"513.900765ms","start":"2026-10-26T01:29:31.815209Z","end":"2026-10-26T01:29:32.32911Z","steps":["trace[882149084] 'process raft request' (duration: 512.207788ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:29:32.329178Z","caller":"traceutil/trace.go:171","msg":"trace[198457294] transaction","detail":"{read_only:false; response_revision:14252; number_of_response:1; }","duration":"475.081531ms","start":"2026-10-26T01:29:31.854066Z","end":"2026-10-26T01:29:32.329148Z","steps":["trace[198457294] 'process raft request' (duration: 474.879615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:29:32.329302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:29:31.815177Z","time spent":"514.043189ms","remote":"127.0.0.1:58214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:29:32.329313Z","caller":"traceutil/trace.go:171","msg":"trace[174159106] transaction","detail":"{read_only:false; response_revision:14253; number_of_response:1; }","duration":"460.831987ms","start":"2026-10-26T01:29:31.868417Z","end":"2026-10-26T01:29:32.329249Z","steps":["trace[174159106] 'process raft request' (duration: 460.58089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:29:32.32939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:29:31.854044Z","time spent":"475.207145ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:29:32.329512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:29:31.86839Z","time spent":"461.026852ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:29:42.317Z","caller":"traceutil/trace.go:171","msg":"trace[1924936281] transaction","detail":"{read_only:false; response_revision:14424; number_of_response:1; }","duration":"232.012118ms","start":"2026-10-26T01:29:42.084943Z","end":"2026-10-26T01:29:42.316956Z","steps":["trace[1924936281] 'process raft request' (duration: 229.689754ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:29:42.316985Z","caller":"traceutil/trace.go:171","msg":"trace[1508331849] linearizableReadLoop","detail":"{readStateIndex:14919; appliedIndex:14918; }","duration":"183.226339ms","start":"2026-10-26T01:29:42.133716Z","end":"2026-10-26T01:29:42.316942Z","steps":["trace[1508331849] 'read index received' (duration: 180.984437ms)","trace[1508331849] 'applied index is now lower than readState.Index' (duration: 2.239072ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:29:42.317176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.442064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T01:29:42.317239Z","caller":"traceutil/trace.go:171","msg":"trace[1352428444] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:14424; }","duration":"183.529017ms","start":"2026-10-26T01:29:42.133696Z","end":"2026-10-26T01:29:42.317225Z","steps":["trace[1352428444] 'agreement among raft nodes before linearized reading' (duration: 183.356342ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:29:42.615669Z","caller":"traceutil/trace.go:171","msg":"trace[107934930] transaction","detail":"{read_only:false; response_revision:14425; number_of_response:1; }","duration":"252.121044ms","start":"2026-10-26T01:29:42.363512Z","end":"2026-10-26T01:29:42.615633Z","steps":["trace[107934930] 'process raft request' (duration: 251.875828ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:29:42.62141Z","caller":"traceutil/trace.go:171","msg":"trace[576452509] transaction","detail":"{read_only:false; response_revision:14426; number_of_response:1; }","duration":"256.442374ms","start":"2026-10-26T01:29:42.364949Z","end":"2026-10-26T01:29:42.621391Z","steps":["trace[576452509] 'process raft request' (duration: 256.220148ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:30:45.489027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12713} {"level":"info","ts":"2026-10-26T01:30:45.577189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12713,"took":"85.440943ms","hash":8163069,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":31158272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T01:30:45.577253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":8163069,"revision":12713,"compact-revision":10389} {"level":"warn","ts":"2026-10-26T01:31:23.576193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.435906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:31:23.5763Z","caller":"traceutil/trace.go:171","msg":"trace[1509628194] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15517; }","duration":"101.60341ms","start":"2026-10-26T01:31:23.474674Z","end":"2026-10-26T01:31:23.576277Z","steps":["trace[1509628194] 'range keys from in-memory index tree' (duration: 101.335313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:23.576449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.673012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-26T01:31:23.576527Z","caller":"traceutil/trace.go:171","msg":"trace[1426215161] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15517; }","duration":"101.798886ms","start":"2026-10-26T01:31:23.47471Z","end":"2026-10-26T01:31:23.576509Z","steps":["trace[1426215161] 'range keys from in-memory index tree' (duration: 101.502838ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:31:29.266347Z","caller":"traceutil/trace.go:171","msg":"trace[1061236094] linearizableReadLoop","detail":"{readStateIndex:16074; appliedIndex:16073; }","duration":"131.671952ms","start":"2026-10-26T01:31:29.134625Z","end":"2026-10-26T01:31:29.266297Z","steps":["trace[1061236094] 'read index received' (duration: 100.608412ms)","trace[1061236094] 'applied index is now lower than readState.Index' (duration: 31.06176ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:31:29.26736Z","caller":"traceutil/trace.go:171","msg":"trace[172624096] transaction","detail":"{read_only:false; response_revision:15543; number_of_response:1; }","duration":"161.688893ms","start":"2026-10-26T01:31:29.10565Z","end":"2026-10-26T01:31:29.267339Z","steps":["trace[172624096] 'process raft request' (duration: 160.14163ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:31:29.267636Z","caller":"traceutil/trace.go:171","msg":"trace[1784614961] transaction","detail":"{read_only:false; response_revision:15544; number_of_response:1; }","duration":"128.745002ms","start":"2026-10-26T01:31:29.138877Z","end":"2026-10-26T01:31:29.267622Z","steps":["trace[1784614961] 'process raft request' (duration: 127.22466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:29.268335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.682118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-26T01:31:29.268495Z","caller":"traceutil/trace.go:171","msg":"trace[1959883839] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15545; }","duration":"133.900144ms","start":"2026-10-26T01:31:29.134581Z","end":"2026-10-26T01:31:29.268481Z","steps":["trace[1959883839] 'agreement among raft nodes before linearized reading' (duration: 133.577956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:29.284412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.696973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:31:29.284545Z","caller":"traceutil/trace.go:171","msg":"trace[724441853] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15546; }","duration":"148.831017ms","start":"2026-10-26T01:31:29.135638Z","end":"2026-10-26T01:31:29.284469Z","steps":["trace[724441853] 'agreement among raft nodes before linearized reading' (duration: 148.60484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:29.284528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.569834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T01:31:29.284639Z","caller":"traceutil/trace.go:171","msg":"trace[1654657820] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15546; }","duration":"146.708919ms","start":"2026-10-26T01:31:29.137902Z","end":"2026-10-26T01:31:29.284611Z","steps":["trace[1654657820] 'agreement among raft nodes before linearized reading' (duration: 146.333238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:30.116105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.06464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-22z4t\" ","response":"range_response_count:1 size:20796"} {"level":"info","ts":"2026-10-26T01:31:30.116197Z","caller":"traceutil/trace.go:171","msg":"trace[2105202560] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-22z4t; range_end:; response_count:1; response_revision:15548; }","duration":"300.250784ms","start":"2026-10-26T01:31:29.815929Z","end":"2026-10-26T01:31:30.11618Z","steps":["trace[2105202560] 'range keys from in-memory index tree' (duration: 299.843073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:30.116232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:31:29.815899Z","time spent":"300.323087ms","remote":"127.0.0.1:58214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20818,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-22z4t\" "} {"level":"info","ts":"2026-10-26T01:35:45.500773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15308} {"level":"info","ts":"2026-10-26T01:35:45.612538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15308,"took":"108.400336ms","hash":3088425545,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":31891456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T01:35:45.612611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3088425545,"revision":15308,"compact-revision":12713} {"level":"info","ts":"2026-10-26T01:37:02.83564Z","caller":"traceutil/trace.go:171","msg":"trace[700106845] linearizableReadLoop","detail":"{readStateIndex:18726; appliedIndex:18725; }","duration":"171.599267ms","start":"2026-10-26T01:37:02.664015Z","end":"2026-10-26T01:37:02.835614Z","steps":["trace[700106845] 'read index received' (duration: 171.223757ms)","trace[700106845] 'applied index is now lower than readState.Index' (duration: 373.77µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:37:02.836025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.983318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:37:02.836082Z","caller":"traceutil/trace.go:171","msg":"trace[2075735151] transaction","detail":"{read_only:false; response_revision:18114; number_of_response:1; }","duration":"207.092255ms","start":"2026-10-26T01:37:02.628957Z","end":"2026-10-26T01:37:02.836049Z","steps":["trace[2075735151] 'process raft request' (duration: 206.398296ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:37:02.836124Z","caller":"traceutil/trace.go:171","msg":"trace[445834236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18114; }","duration":"172.098381ms","start":"2026-10-26T01:37:02.66401Z","end":"2026-10-26T01:37:02.836109Z","steps":["trace[445834236] 'agreement among raft nodes before linearized reading' (duration: 171.938976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:37:10.273386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.654823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gfrnc\" ","response":"range_response_count:1 size:22122"} {"level":"warn","ts":"2026-10-26T01:37:10.27351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.756929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T01:37:10.273595Z","caller":"traceutil/trace.go:171","msg":"trace[1630681736] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:18165; }","duration":"225.863802ms","start":"2026-10-26T01:37:10.047709Z","end":"2026-10-26T01:37:10.273573Z","steps":["trace[1630681736] 'count revisions from in-memory index tree' (duration: 225.691638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:37:10.273683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.879774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T01:37:10.27377Z","caller":"traceutil/trace.go:171","msg":"trace[1496546470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18165; }","duration":"286.111421ms","start":"2026-10-26T01:37:09.987639Z","end":"2026-10-26T01:37:10.27375Z","steps":["trace[1496546470] 'range keys from in-memory index tree' (duration: 285.777572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:37:10.273635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.502637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T01:37:10.273952Z","caller":"traceutil/trace.go:171","msg":"trace[1883397257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18165; }","duration":"280.837325ms","start":"2026-10-26T01:37:09.993101Z","end":"2026-10-26T01:37:10.273938Z","steps":["trace[1883397257] 'range keys from in-memory index tree' (duration: 280.362372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:37:10.273971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.04101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-22z4t\" ","response":"range_response_count:1 size:20899"} {"level":"info","ts":"2026-10-26T01:37:10.273474Z","caller":"traceutil/trace.go:171","msg":"trace[1251178092] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gfrnc; range_end:; response_count:1; response_revision:18165; }","duration":"291.775727ms","start":"2026-10-26T01:37:09.98168Z","end":"2026-10-26T01:37:10.273455Z","steps":["trace[1251178092] 'range keys from in-memory index tree' (duration: 291.470539ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:37:10.27414Z","caller":"traceutil/trace.go:171","msg":"trace[1828272655] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-22z4t; range_end:; response_count:1; response_revision:18165; }","duration":"172.879413ms","start":"2026-10-26T01:37:10.101234Z","end":"2026-10-26T01:37:10.274113Z","steps":["trace[1828272655] 'range keys from in-memory index tree' (duration: 171.894576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:37:10.813155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.089364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:37:10.813238Z","caller":"traceutil/trace.go:171","msg":"trace[1830881945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18170; }","duration":"379.243368ms","start":"2026-10-26T01:37:10.433975Z","end":"2026-10-26T01:37:10.813218Z","steps":["trace[1830881945] 'range keys from in-memory index tree' (duration: 378.928529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:37:10.813283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:37:10.43394Z","time spent":"379.33428ms","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T01:37:10.814341Z","caller":"traceutil/trace.go:171","msg":"trace[599352939] transaction","detail":"{read_only:false; response_revision:18172; number_of_response:1; }","duration":"239.238551ms","start":"2026-10-26T01:37:10.575068Z","end":"2026-10-26T01:37:10.814307Z","steps":["trace[599352939] 'process raft request' (duration: 239.096717ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:37:10.814371Z","caller":"traceutil/trace.go:171","msg":"trace[325426725] linearizableReadLoop","detail":"{readStateIndex:18787; appliedIndex:18784; }","duration":"151.090393ms","start":"2026-10-26T01:37:10.663256Z","end":"2026-10-26T01:37:10.814347Z","steps":["trace[325426725] 'read index received' (duration: 145.634112ms)","trace[325426725] 'applied index is now lower than readState.Index' (duration: 5.454981ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:37:10.814566Z","caller":"traceutil/trace.go:171","msg":"trace[724200579] transaction","detail":"{read_only:false; response_revision:18171; number_of_response:1; }","duration":"272.767364ms","start":"2026-10-26T01:37:10.541787Z","end":"2026-10-26T01:37:10.814554Z","steps":["trace[724200579] 'process raft request' (duration: 267.17256ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:37:10.814662Z","caller":"traceutil/trace.go:171","msg":"trace[2133913593] transaction","detail":"{read_only:false; response_revision:18173; number_of_response:1; }","duration":"212.104003ms","start":"2026-10-26T01:37:10.602534Z","end":"2026-10-26T01:37:10.814638Z","steps":["trace[2133913593] 'process raft request' (duration: 211.734673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:37:10.814856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.581986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:37:10.814894Z","caller":"traceutil/trace.go:171","msg":"trace[1946441820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18173; }","duration":"151.671038ms","start":"2026-10-26T01:37:10.663213Z","end":"2026-10-26T01:37:10.814884Z","steps":["trace[1946441820] 'agreement among raft nodes before linearized reading' (duration: 151.574136ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:39:29.097375Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:39:29.104729Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T01:39:29.104841Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T01:40:45.512367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17476} {"level":"info","ts":"2026-10-26T01:40:45.634509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17476,"took":"118.270588ms","hash":1655255798,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":34533376,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T01:40:45.634588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1655255798,"revision":17476,"compact-revision":15308} {"level":"warn","ts":"2026-10-26T01:42:16.348951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.655056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T01:42:16.349092Z","caller":"traceutil/trace.go:171","msg":"trace[839124943] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:20550; }","duration":"168.919223ms","start":"2026-10-26T01:42:16.180143Z","end":"2026-10-26T01:42:16.349062Z","steps":["trace[839124943] 'count revisions from in-memory index tree' (duration: 168.533003ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:42:32.34013Z","caller":"traceutil/trace.go:171","msg":"trace[902006606] linearizableReadLoop","detail":"{readStateIndex:21323; appliedIndex:21322; }","duration":"274.029378ms","start":"2026-10-26T01:42:32.066064Z","end":"2026-10-26T01:42:32.340093Z","steps":["trace[902006606] 'read index received' (duration: 50.283145ms)","trace[902006606] 'applied index is now lower than readState.Index' (duration: 223.744713ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:42:32.340217Z","caller":"traceutil/trace.go:171","msg":"trace[564716143] transaction","detail":"{read_only:false; response_revision:20627; number_of_response:1; }","duration":"280.060964ms","start":"2026-10-26T01:42:32.060119Z","end":"2026-10-26T01:42:32.34018Z","steps":["trace[564716143] 'process raft request' (duration: 279.623392ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:42:32.340289Z","caller":"traceutil/trace.go:171","msg":"trace[1501827943] transaction","detail":"{read_only:false; response_revision:20628; number_of_response:1; }","duration":"268.171587ms","start":"2026-10-26T01:42:32.072103Z","end":"2026-10-26T01:42:32.340275Z","steps":["trace[1501827943] 'process raft request' (duration: 267.884839ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:42:32.340396Z","caller":"traceutil/trace.go:171","msg":"trace[1682053189] transaction","detail":"{read_only:false; response_revision:20629; number_of_response:1; }","duration":"266.8546ms","start":"2026-10-26T01:42:32.073504Z","end":"2026-10-26T01:42:32.340359Z","steps":["trace[1682053189] 'process raft request' (duration: 266.537852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:42:32.340494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.724674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-26T01:42:32.340497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.423309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T01:42:32.34056Z","caller":"traceutil/trace.go:171","msg":"trace[1545102358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20629; }","duration":"263.834107ms","start":"2026-10-26T01:42:32.076708Z","end":"2026-10-26T01:42:32.340542Z","steps":["trace[1545102358] 'agreement among raft nodes before linearized reading' (duration: 263.626932ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:42:32.340579Z","caller":"traceutil/trace.go:171","msg":"trace[304991650] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20629; }","duration":"274.568433ms","start":"2026-10-26T01:42:32.065984Z","end":"2026-10-26T01:42:32.340552Z","steps":["trace[304991650] 'agreement among raft nodes before linearized reading' (duration: 274.302115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:42:32.340607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.315978ms","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-26T01:42:32.340678Z","caller":"traceutil/trace.go:171","msg":"trace[907817321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20629; }","duration":"257.4059ms","start":"2026-10-26T01:42:32.08325Z","end":"2026-10-26T01:42:32.340656Z","steps":["trace[907817321] 'agreement among raft nodes before linearized reading' (duration: 257.233226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:42:32.663344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.135022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:42:32.663516Z","caller":"traceutil/trace.go:171","msg":"trace[1678517416] transaction","detail":"{read_only:false; response_revision:20630; number_of_response:1; }","duration":"317.606729ms","start":"2026-10-26T01:42:32.345889Z","end":"2026-10-26T01:42:32.663496Z","steps":["trace[1678517416] 'process raft request' (duration: 129.23791ms)","trace[1678517416] 'compare' (duration: 187.750382ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:42:32.66358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:42:32.34586Z","time spent":"317.693412ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:42:32.664136Z","caller":"traceutil/trace.go:171","msg":"trace[738232722] linearizableReadLoop","detail":"{readStateIndex:21327; appliedIndex:21325; }","duration":"317.609079ms","start":"2026-10-26T01:42:32.346517Z","end":"2026-10-26T01:42:32.664126Z","steps":["trace[738232722] 'read index received' (duration: 128.630283ms)","trace[738232722] 'applied index is now lower than readState.Index' (duration: 188.977726ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:42:32.664207Z","caller":"traceutil/trace.go:171","msg":"trace[599053661] transaction","detail":"{read_only:false; response_revision:20632; number_of_response:1; }","duration":"317.555638ms","start":"2026-10-26T01:42:32.346595Z","end":"2026-10-26T01:42:32.66415Z","steps":["trace[599053661] 'process raft request' (duration: 317.475346ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:42:32.66421Z","caller":"traceutil/trace.go:171","msg":"trace[1704283756] transaction","detail":"{read_only:false; response_revision:20631; number_of_response:1; }","duration":"317.826105ms","start":"2026-10-26T01:42:32.346325Z","end":"2026-10-26T01:42:32.664151Z","steps":["trace[1704283756] 'process raft request' (duration: 317.114046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:42:32.664319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.796294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T01:42:32.664367Z","caller":"traceutil/trace.go:171","msg":"trace[1453443497] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20632; }","duration":"317.875047ms","start":"2026-10-26T01:42:32.346478Z","end":"2026-10-26T01:42:32.664353Z","steps":["trace[1453443497] 'agreement among raft nodes before linearized reading' (duration: 317.695492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:42:32.664395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:42:32.346585Z","time spent":"317.726433ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:42:32.664411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:42:32.346467Z","time spent":"317.936199ms","remote":"127.0.0.1:58214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12454,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-26T01:42:32.66446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:42:32.346291Z","time spent":"318.036711ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:42:32.981891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.362435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:6012"} {"level":"warn","ts":"2026-10-26T01:42:32.981928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.639303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:42:32.982015Z","caller":"traceutil/trace.go:171","msg":"trace[366742973] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:20632; }","duration":"218.542161ms","start":"2026-10-26T01:42:32.763444Z","end":"2026-10-26T01:42:32.981986Z","steps":["trace[366742973] 'range keys from in-memory index tree' (duration: 218.143949ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:42:32.982031Z","caller":"traceutil/trace.go:171","msg":"trace[1771853991] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:20632; }","duration":"112.835018ms","start":"2026-10-26T01:42:32.869167Z","end":"2026-10-26T01:42:32.982002Z","steps":["trace[1771853991] 'count revisions from in-memory index tree' (duration: 112.476158ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:42:33.403188Z","caller":"traceutil/trace.go:171","msg":"trace[1382018604] transaction","detail":"{read_only:false; response_revision:20634; number_of_response:1; }","duration":"227.26548ms","start":"2026-10-26T01:42:33.175892Z","end":"2026-10-26T01:42:33.403158Z","steps":["trace[1382018604] 'process raft request' (duration: 227.096766ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:45:45.530673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19896} {"level":"info","ts":"2026-10-26T01:45:45.661428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19896,"took":"123.080696ms","hash":1598591781,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":30793728,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T01:45:45.661516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1598591781,"revision":19896,"compact-revision":17476} {"level":"warn","ts":"2026-10-26T01:46:36.047048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.698162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:46:36.04714Z","caller":"traceutil/trace.go:171","msg":"trace[1754644278] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:23125; }","duration":"289.836875ms","start":"2026-10-26T01:46:35.757284Z","end":"2026-10-26T01:46:36.047121Z","steps":["trace[1754644278] 'count revisions from in-memory index tree' (duration: 289.592818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:36.047484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.042941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-10-26T01:46:36.047521Z","caller":"traceutil/trace.go:171","msg":"trace[1928569205] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23125; }","duration":"266.119133ms","start":"2026-10-26T01:46:35.781393Z","end":"2026-10-26T01:46:36.047512Z","steps":["trace[1928569205] 'range keys from in-memory index tree' (duration: 265.828775ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:48:03.957282Z","caller":"traceutil/trace.go:171","msg":"trace[1985949408] transaction","detail":"{read_only:false; response_revision:24075; number_of_response:1; }","duration":"106.658871ms","start":"2026-10-26T01:48:03.850591Z","end":"2026-10-26T01:48:03.95725Z","steps":["trace[1985949408] 'process raft request' (duration: 40.476337ms)","trace[1985949408] 'compare' (duration: 66.072511ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:49:59.305013Z","caller":"traceutil/trace.go:171","msg":"trace[840692365] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"162.870134ms","start":"2026-10-26T01:49:59.142119Z","end":"2026-10-26T01:49:59.304989Z","steps":["trace[840692365] 'process raft request' (duration: 162.702049ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:49:59.452992Z","caller":"traceutil/trace.go:171","msg":"trace[1289620141] linearizableReadLoop","detail":"{readStateIndex:25954; appliedIndex:25952; }","duration":"166.935194ms","start":"2026-10-26T01:49:59.286027Z","end":"2026-10-26T01:49:59.452962Z","steps":["trace[1289620141] 'read index received' (duration: 18.872871ms)","trace[1289620141] 'applied index is now lower than readState.Index' (duration: 148.061013ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:49:59.453241Z","caller":"traceutil/trace.go:171","msg":"trace[1629446872] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"307.572766ms","start":"2026-10-26T01:49:59.145602Z","end":"2026-10-26T01:49:59.453175Z","steps":["trace[1629446872] 'process raft request' (duration: 298.264524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:49:59.453261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.197551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:49:59.453632Z","caller":"traceutil/trace.go:171","msg":"trace[4046797] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25134; }","duration":"167.637273ms","start":"2026-10-26T01:49:59.285972Z","end":"2026-10-26T01:49:59.453609Z","steps":["trace[4046797] 'agreement among raft nodes before linearized reading' (duration: 167.17291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:49:59.453859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:49:59.145584Z","time spent":"307.758521ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:49:59.920818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.782621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T01:49:59.920867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.944565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-26T01:49:59.920924Z","caller":"traceutil/trace.go:171","msg":"trace[361459698] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25135; }","duration":"190.963856ms","start":"2026-10-26T01:49:59.729929Z","end":"2026-10-26T01:49:59.920893Z","steps":["trace[361459698] 'count revisions from in-memory index tree' (duration: 190.705769ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:49:59.920978Z","caller":"traceutil/trace.go:171","msg":"trace[1907097848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25135; }","duration":"153.141641ms","start":"2026-10-26T01:49:59.767813Z","end":"2026-10-26T01:49:59.920955Z","steps":["trace[1907097848] 'range keys from in-memory index tree' (duration: 152.656827ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:50:00.138244Z","caller":"traceutil/trace.go:171","msg":"trace[901584094] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"129.068948ms","start":"2026-10-26T01:50:00.009148Z","end":"2026-10-26T01:50:00.138217Z","steps":["trace[901584094] 'process raft request' (duration: 128.975776ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:50:00.138189Z","caller":"traceutil/trace.go:171","msg":"trace[702796689] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"209.737744ms","start":"2026-10-26T01:49:59.92841Z","end":"2026-10-26T01:50:00.138148Z","steps":["trace[702796689] 'process raft request' (duration: 141.919336ms)","trace[702796689] 'compare' (duration: 67.541631ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:50:00.138325Z","caller":"traceutil/trace.go:171","msg":"trace[844048085] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"194.79167ms","start":"2026-10-26T01:49:59.943481Z","end":"2026-10-26T01:50:00.138272Z","steps":["trace[844048085] 'process raft request' (duration: 194.584364ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:50:00.138785Z","caller":"traceutil/trace.go:171","msg":"trace[880668494] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"127.429024ms","start":"2026-10-26T01:50:00.011224Z","end":"2026-10-26T01:50:00.138653Z","steps":["trace[880668494] 'process raft request' (duration: 126.952851ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:50:00.325414Z","caller":"traceutil/trace.go:171","msg":"trace[1509138411] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"183.106043ms","start":"2026-10-26T01:50:00.142276Z","end":"2026-10-26T01:50:00.325382Z","steps":["trace[1509138411] 'process raft request' (duration: 180.900173ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:50:00.325599Z","caller":"traceutil/trace.go:171","msg":"trace[1629138369] linearizableReadLoop","detail":"{readStateIndex:25963; appliedIndex:25961; }","duration":"117.962777ms","start":"2026-10-26T01:50:00.207614Z","end":"2026-10-26T01:50:00.325577Z","steps":["trace[1629138369] 'read index received' (duration: 115.550372ms)","trace[1629138369] 'applied index is now lower than readState.Index' (duration: 2.411455ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:50:00.32575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.116651ms","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:6"} {"level":"info","ts":"2026-10-26T01:50:00.325809Z","caller":"traceutil/trace.go:171","msg":"trace[699625152] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25143; }","duration":"118.265095ms","start":"2026-10-26T01:50:00.20753Z","end":"2026-10-26T01:50:00.325795Z","steps":["trace[699625152] 'agreement among raft nodes before linearized reading' (duration: 118.126642ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:50:00.32602Z","caller":"traceutil/trace.go:171","msg":"trace[1404037881] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"141.249648ms","start":"2026-10-26T01:50:00.18476Z","end":"2026-10-26T01:50:00.32601Z","steps":["trace[1404037881] 'process raft request' (duration: 140.699463ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:50:45.539516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22223} {"level":"info","ts":"2026-10-26T01:50:45.660688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22223,"took":"117.219537ms","hash":2047600697,"current-db-size-bytes":57032704,"current-db-size":"57 MB","current-db-size-in-use-bytes":45137920,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-26T01:50:45.660781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2047600697,"revision":22223,"compact-revision":19896} {"level":"info","ts":"2026-10-26T01:51:00.670103Z","caller":"traceutil/trace.go:171","msg":"trace[1718204013] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"290.597845ms","start":"2026-10-26T01:51:00.37948Z","end":"2026-10-26T01:51:00.670078Z","steps":["trace[1718204013] 'process raft request' (duration: 290.449531ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:51:00.703242Z","caller":"traceutil/trace.go:171","msg":"trace[5044079] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"322.300765ms","start":"2026-10-26T01:51:00.380898Z","end":"2026-10-26T01:51:00.703199Z","steps":["trace[5044079] 'process raft request' (duration: 322.048638ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:51:00.703347Z","caller":"traceutil/trace.go:171","msg":"trace[729007926] linearizableReadLoop","detail":"{readStateIndex:26550; appliedIndex:26548; }","duration":"286.70843ms","start":"2026-10-26T01:51:00.416607Z","end":"2026-10-26T01:51:00.703315Z","steps":["trace[729007926] 'read index received' (duration: 253.253643ms)","trace[729007926] 'applied index is now lower than readState.Index' (duration: 33.453087ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:51:00.70345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:00.380892Z","time spent":"322.446798ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:51:00.703601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.72858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T01:51:00.703652Z","caller":"traceutil/trace.go:171","msg":"trace[1175009365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25718; }","duration":"276.850194ms","start":"2026-10-26T01:51:00.426788Z","end":"2026-10-26T01:51:00.703639Z","steps":["trace[1175009365] 'agreement among raft nodes before linearized reading' (duration: 276.681579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:00.703683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.607603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-26T01:51:00.70385Z","caller":"traceutil/trace.go:171","msg":"trace[374984866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25718; }","duration":"260.795438ms","start":"2026-10-26T01:51:00.443021Z","end":"2026-10-26T01:51:00.703816Z","steps":["trace[374984866] 'agreement among raft nodes before linearized reading' (duration: 260.48722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:00.704116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.870864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:51:00.704206Z","caller":"traceutil/trace.go:171","msg":"trace[1463709489] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25718; }","duration":"287.613564ms","start":"2026-10-26T01:51:00.416555Z","end":"2026-10-26T01:51:00.704169Z","steps":["trace[1463709489] 'agreement among raft nodes before linearized reading' (duration: 286.883304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:01.065997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.20215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:51:01.066086Z","caller":"traceutil/trace.go:171","msg":"trace[2085868505] linearizableReadLoop","detail":"{readStateIndex:26554; appliedIndex:26553; }","duration":"212.860839ms","start":"2026-10-26T01:51:00.853211Z","end":"2026-10-26T01:51:01.066072Z","steps":["trace[2085868505] 'read index received' (duration: 41.463794ms)","trace[2085868505] 'applied index is now lower than readState.Index' (duration: 171.396075ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:51:01.066171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.954752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:51:01.066198Z","caller":"traceutil/trace.go:171","msg":"trace[226926154] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25720; }","duration":"213.008893ms","start":"2026-10-26T01:51:00.853177Z","end":"2026-10-26T01:51:01.066186Z","steps":["trace[226926154] 'agreement among raft nodes before linearized reading' (duration: 212.932651ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:51:01.066443Z","caller":"traceutil/trace.go:171","msg":"trace[1817362421] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"356.151973ms","start":"2026-10-26T01:51:00.71024Z","end":"2026-10-26T01:51:01.066392Z","steps":["trace[1817362421] 'process raft request' (duration: 184.463279ms)","trace[1817362421] 'compare' (duration: 170.974164ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:51:01.066504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:00.710218Z","time spent":"356.251196ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:51:01.122131Z","caller":"traceutil/trace.go:171","msg":"trace[551451509] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"247.91353ms","start":"2026-10-26T01:51:00.874189Z","end":"2026-10-26T01:51:01.122102Z","steps":["trace[551451509] 'process raft request' (duration: 247.779476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:01.44787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.366148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:51:01.447975Z","caller":"traceutil/trace.go:171","msg":"trace[1464665692] linearizableReadLoop","detail":"{readStateIndex:26556; appliedIndex:26555; }","duration":"175.397044ms","start":"2026-10-26T01:51:01.272562Z","end":"2026-10-26T01:51:01.447959Z","steps":["trace[1464665692] 'read index received' (duration: 42.321µs)","trace[1464665692] 'applied index is now lower than readState.Index' (duration: 175.353523ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:51:01.44806Z","caller":"traceutil/trace.go:171","msg":"trace[1241481189] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"303.489226ms","start":"2026-10-26T01:51:01.14456Z","end":"2026-10-26T01:51:01.448049Z","steps":["trace[1241481189] 'process raft request' (duration: 108.807179ms)","trace[1241481189] 'compare' (duration: 193.993308ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:51:01.44812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:01.144535Z","time spent":"303.555607ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:51:01.448149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.665242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:51:01.448244Z","caller":"traceutil/trace.go:171","msg":"trace[790438232] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25722; }","duration":"144.796045ms","start":"2026-10-26T01:51:01.303422Z","end":"2026-10-26T01:51:01.448218Z","steps":["trace[790438232] 'agreement among raft nodes before linearized reading' (duration: 144.64473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:01.44853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.965749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-26T01:51:01.448577Z","caller":"traceutil/trace.go:171","msg":"trace[1411649822] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25722; }","duration":"176.047381ms","start":"2026-10-26T01:51:01.272519Z","end":"2026-10-26T01:51:01.448566Z","steps":["trace[1411649822] 'agreement among raft nodes before linearized reading' (duration: 175.911607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:01.778899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.91644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:51:01.779011Z","caller":"traceutil/trace.go:171","msg":"trace[155708073] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"305.415058ms","start":"2026-10-26T01:51:01.473577Z","end":"2026-10-26T01:51:01.778992Z","steps":["trace[155708073] 'process raft request' (duration: 140.307253ms)","trace[155708073] 'compare' (duration: 164.58737ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:51:01.779071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:01.473557Z","time spent":"305.48549ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:51:01.804205Z","caller":"traceutil/trace.go:171","msg":"trace[1041498955] linearizableReadLoop","detail":"{readStateIndex:26558; appliedIndex:26557; }","duration":"119.758676ms","start":"2026-10-26T01:51:01.684424Z","end":"2026-10-26T01:51:01.804182Z","steps":["trace[1041498955] 'read index received' (duration: 119.628623ms)","trace[1041498955] 'applied index is now lower than readState.Index' (duration: 129.083µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:51:01.804289Z","caller":"traceutil/trace.go:171","msg":"trace[1583499737] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"328.961216ms","start":"2026-10-26T01:51:01.475296Z","end":"2026-10-26T01:51:01.804258Z","steps":["trace[1583499737] 'process raft request' (duration: 328.767761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:01.804368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.04991ms","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:8"} {"level":"info","ts":"2026-10-26T01:51:01.804403Z","caller":"traceutil/trace.go:171","msg":"trace[1488536956] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25724; }","duration":"104.140672ms","start":"2026-10-26T01:51:01.700252Z","end":"2026-10-26T01:51:01.804393Z","steps":["trace[1488536956] 'agreement among raft nodes before linearized reading' (duration: 104.05749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:01.80446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:01.475281Z","time spent":"329.086369ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:51:01.804508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.063114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-26T01:51:01.804595Z","caller":"traceutil/trace.go:171","msg":"trace[1959945574] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25724; }","duration":"120.206828ms","start":"2026-10-26T01:51:01.684365Z","end":"2026-10-26T01:51:01.804572Z","steps":["trace[1959945574] 'agreement among raft nodes before linearized reading' (duration: 119.93798ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:51:32.061225Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068a8.wal"} {"level":"info","ts":"2026-10-26T01:55:45.547425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25606} {"level":"info","ts":"2026-10-26T01:55:45.725457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25606,"took":"173.797994ms","hash":1816546437,"current-db-size-bytes":58613760,"current-db-size":"59 MB","current-db-size-in-use-bytes":35827712,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T01:55:45.725509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1816546437,"revision":25606,"compact-revision":22223} {"level":"info","ts":"2026-10-26T01:57:08.768586Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:57:08.775438Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T01:57:08.775576Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T02:00:45.554897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28475} {"level":"info","ts":"2026-10-26T02:00:45.711642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28475,"took":"152.530502ms","hash":1441204103,"current-db-size-bytes":58613760,"current-db-size":"59 MB","current-db-size-in-use-bytes":31551488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T02:00:45.711757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1441204103,"revision":28475,"compact-revision":25606} {"level":"info","ts":"2026-10-26T02:05:45.563529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30763} {"level":"info","ts":"2026-10-26T02:05:45.714533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30763,"took":"144.823892ms","hash":1345609017,"current-db-size-bytes":58613760,"current-db-size":"59 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T02:05:45.71462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1345609017,"revision":30763,"compact-revision":28475} {"level":"info","ts":"2026-10-26T02:10:45.570976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33051} {"level":"info","ts":"2026-10-26T02:10:45.752752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33051,"took":"173.148543ms","hash":309818807,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":30785536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T02:10:45.752867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":309818807,"revision":33051,"compact-revision":30763} {"level":"info","ts":"2026-10-26T02:15:45.578206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35336} {"level":"info","ts":"2026-10-26T02:15:45.733351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35336,"took":"149.10399ms","hash":1066463125,"current-db-size-bytes":60100608,"current-db-size":"60 MB","current-db-size-in-use-bytes":31510528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T02:15:45.733418Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1066463125,"revision":35336,"compact-revision":33051} {"level":"info","ts":"2026-10-26T02:18:28.587552Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T02:18:28.59649Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T02:18:28.596663Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T02:20:45.588433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37571} {"level":"info","ts":"2026-10-26T02:20:45.731449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37571,"took":"135.960217ms","hash":1789148294,"current-db-size-bytes":60944384,"current-db-size":"61 MB","current-db-size-in-use-bytes":31875072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T02:20:45.731532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1789148294,"revision":37571,"compact-revision":35336} {"level":"info","ts":"2026-10-26T02:25:45.596804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39844} {"level":"info","ts":"2026-10-26T02:25:45.741046Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39844,"took":"140.228955ms","hash":3495275715,"current-db-size-bytes":60944384,"current-db-size":"61 MB","current-db-size-in-use-bytes":30478336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T02:25:45.741113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3495275715,"revision":39844,"compact-revision":37571}