{"level":"warn","ts":"2026-05-24T22:08:04.686898Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T22:08:04.686977Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-05-24T22:08:04.687041Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T22:08:04.687052Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-05-24T22:08:04.68707Z","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-05-24T22:08:04.687718Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-05-24T22:08:04.687792Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-05-24T22:08:04.690839Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.807565ms"} {"level":"info","ts":"2026-05-24T22:08:04.697315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-05-24T22:08:04.697393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-24T22:08:04.697411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-05-24T22:08:04.697427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-24T22:08:04.697436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-05-24T22:08:04.697475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-05-24T22:08:04.701791Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-24T22:08:04.703035Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-24T22:08:04.704484Z","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-05-24T22:08:04.707251Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-24T22:08:04.707452Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-24T22:08:04.707612Z","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-05-24T22:08:04.707677Z","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-05-24T22:08:04.707687Z","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-05-24T22:08:04.71264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-05-24T22:08:04.712912Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-05-24T22:08:04.713862Z","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-05-24T22:08:04.714Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-05-24T22:08:04.714031Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-05-24T22:08:04.714287Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-24T22:08:04.714356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-24T22:08:04.898308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-24T22:08:04.898364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-24T22:08:04.898442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-05-24T22:08:04.89846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-05-24T22:08:04.898474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-05-24T22:08:04.898485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-05-24T22:08:04.898495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-05-24T22:08:04.899216Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T22:08:04.899955Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T22:08:04.899952Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-24T22:08:04.900033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T22:08:04.900339Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-24T22:08:04.900416Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T22:08:04.900471Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-24T22:08:04.900488Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T22:08:04.900521Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T22:08:04.90266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-05-24T22:08:04.904754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-24T22:08:09.865209Z","caller":"traceutil/trace.go:171","msg":"trace[1857684378] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"120.677523ms","start":"2026-05-24T22:08:09.744507Z","end":"2026-05-24T22:08:09.865185Z","steps":["trace[1857684378] 'process raft request' (duration: 120.537619ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:08:20.013878Z","caller":"traceutil/trace.go:171","msg":"trace[602604246] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"102.513836ms","start":"2026-05-24T22:08:19.911343Z","end":"2026-05-24T22:08:20.013856Z","steps":["trace[602604246] 'process raft request' (duration: 102.006051ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:08:20.243639Z","caller":"traceutil/trace.go:171","msg":"trace[28608677] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"224.914373ms","start":"2026-05-24T22:08:20.018706Z","end":"2026-05-24T22:08:20.24362Z","steps":["trace[28608677] 'process raft request' (duration: 224.794909ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:08:46.05241Z","caller":"traceutil/trace.go:171","msg":"trace[1888747664] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"108.002532ms","start":"2026-05-24T22:08:45.944345Z","end":"2026-05-24T22:08:46.052347Z","steps":["trace[1888747664] 'process raft request' (duration: 104.600489ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:08:47.170822Z","caller":"traceutil/trace.go:171","msg":"trace[742609958] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"176.9941ms","start":"2026-05-24T22:08:46.993788Z","end":"2026-05-24T22:08:47.170782Z","steps":["trace[742609958] 'process raft request' (duration: 176.426523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:08:47.731028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.699457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-twbdp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-24T22:08:47.73116Z","caller":"traceutil/trace.go:171","msg":"trace[1076570219] range","detail":"{range_begin:/registry/pods/kube-system/cilium-twbdp; range_end:; response_count:1; response_revision:631; }","duration":"257.868872ms","start":"2026-05-24T22:08:47.473268Z","end":"2026-05-24T22:08:47.731137Z","steps":["trace[1076570219] 'range keys from in-memory index tree' (duration: 257.502392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:10:14.98064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.132168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-24T22:10:14.980771Z","caller":"traceutil/trace.go:171","msg":"trace[965396870] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1855; }","duration":"547.378325ms","start":"2026-05-24T22:10:14.433369Z","end":"2026-05-24T22:10:14.980747Z","steps":["trace[965396870] 'range keys from in-memory index tree' (duration: 546.935942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:10:14.980812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.668258ms","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":"warn","ts":"2026-05-24T22:10:14.980817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:10:14.433342Z","time spent":"547.463277ms","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-24T22:10:14.98083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.849144ms","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-05-24T22:10:14.98087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.847479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T22:10:14.980916Z","caller":"traceutil/trace.go:171","msg":"trace[1524128176] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1855; }","duration":"560.826592ms","start":"2026-05-24T22:10:14.420064Z","end":"2026-05-24T22:10:14.980891Z","steps":["trace[1524128176] 'count revisions from in-memory index tree' (duration: 560.554415ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:10:14.980945Z","caller":"traceutil/trace.go:171","msg":"trace[24903201] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1855; }","duration":"526.976752ms","start":"2026-05-24T22:10:14.45395Z","end":"2026-05-24T22:10:14.980927Z","steps":["trace[24903201] 'count revisions from in-memory index tree' (duration: 526.780527ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:10:14.980948Z","caller":"traceutil/trace.go:171","msg":"trace[1087112313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1855; }","duration":"367.006948ms","start":"2026-05-24T22:10:14.613916Z","end":"2026-05-24T22:10:14.980923Z","steps":["trace[1087112313] 'range keys from in-memory index tree' (duration: 366.585286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:10:14.980973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:10:14.420043Z","time spent":"560.917225ms","remote":"127.0.0.1:42722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-24T22:10:14.980984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:10:14.453937Z","time spent":"527.037025ms","remote":"127.0.0.1:42770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-24T22:10:14.981094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:10:14.613898Z","time spent":"367.175733ms","remote":"127.0.0.1:42572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-24T22:10:15.12333Z","caller":"traceutil/trace.go:171","msg":"trace[1338585946] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"137.063291ms","start":"2026-05-24T22:10:14.986223Z","end":"2026-05-24T22:10:15.123286Z","steps":["trace[1338585946] 'process raft request' (duration: 104.156589ms)","trace[1338585946] 'compare' (duration: 32.594843ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:10:15.123643Z","caller":"traceutil/trace.go:171","msg":"trace[1989879970] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"135.057396ms","start":"2026-05-24T22:10:14.988563Z","end":"2026-05-24T22:10:15.123621Z","steps":["trace[1989879970] 'process raft request' (duration: 134.895272ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:10:15.123821Z","caller":"traceutil/trace.go:171","msg":"trace[1873732710] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1924; }","duration":"134.391387ms","start":"2026-05-24T22:10:14.98915Z","end":"2026-05-24T22:10:15.123542Z","steps":["trace[1873732710] 'read index received' (duration: 17.684995ms)","trace[1873732710] 'applied index is now lower than readState.Index' (duration: 116.705292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T22:10:15.123835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.655044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:10:15.123894Z","caller":"traceutil/trace.go:171","msg":"trace[698064529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"134.753337ms","start":"2026-05-24T22:10:14.989125Z","end":"2026-05-24T22:10:15.123878Z","steps":["trace[698064529] 'agreement among raft nodes before linearized reading' (duration: 134.638394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:10:15.124001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.082894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-24T22:10:15.124038Z","caller":"traceutil/trace.go:171","msg":"trace[14431450] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"121.152976ms","start":"2026-05-24T22:10:15.002875Z","end":"2026-05-24T22:10:15.124028Z","steps":["trace[14431450] 'agreement among raft nodes before linearized reading' (duration: 121.027003ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:10:15.391568Z","caller":"traceutil/trace.go:171","msg":"trace[630161696] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"143.702327ms","start":"2026-05-24T22:10:15.247829Z","end":"2026-05-24T22:10:15.391531Z","steps":["trace[630161696] 'process raft request' (duration: 143.333787ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:10:15.391688Z","caller":"traceutil/trace.go:171","msg":"trace[1176921980] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"186.473687ms","start":"2026-05-24T22:10:15.205108Z","end":"2026-05-24T22:10:15.391581Z","steps":["trace[1176921980] 'process raft request' (duration: 119.697885ms)","trace[1176921980] 'compare' (duration: 66.191866ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:10:27.638518Z","caller":"traceutil/trace.go:171","msg":"trace[2061066771] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"180.563191ms","start":"2026-05-24T22:10:27.457915Z","end":"2026-05-24T22:10:27.638478Z","steps":["trace[2061066771] 'process raft request' (duration: 88.965704ms)","trace[2061066771] 'compare' (duration: 91.33364ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:10:27.638506Z","caller":"traceutil/trace.go:171","msg":"trace[1960394806] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2030; }","duration":"177.059153ms","start":"2026-05-24T22:10:27.461409Z","end":"2026-05-24T22:10:27.638468Z","steps":["trace[1960394806] 'read index received' (duration: 59.330593ms)","trace[1960394806] 'applied index is now lower than readState.Index' (duration: 117.72714ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:10:27.638685Z","caller":"traceutil/trace.go:171","msg":"trace[907732434] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"180.327445ms","start":"2026-05-24T22:10:27.458341Z","end":"2026-05-24T22:10:27.638669Z","steps":["trace[907732434] 'process raft request' (duration: 180.071837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:10:27.639007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.575868ms","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-05-24T22:10:27.63904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.751748ms","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-05-24T22:10:27.639057Z","caller":"traceutil/trace.go:171","msg":"trace[843141254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1963; }","duration":"177.694781ms","start":"2026-05-24T22:10:27.461351Z","end":"2026-05-24T22:10:27.639046Z","steps":["trace[843141254] 'agreement among raft nodes before linearized reading' (duration: 177.495655ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:10:27.639101Z","caller":"traceutil/trace.go:171","msg":"trace[919082976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1963; }","duration":"174.871371ms","start":"2026-05-24T22:10:27.464216Z","end":"2026-05-24T22:10:27.639087Z","steps":["trace[919082976] 'agreement among raft nodes before linearized reading' (duration: 174.670366ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:10:27.948198Z","caller":"traceutil/trace.go:171","msg":"trace[1882683165] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"304.900437ms","start":"2026-05-24T22:10:27.643267Z","end":"2026-05-24T22:10:27.948167Z","steps":["trace[1882683165] 'process raft request' (duration: 304.722981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:10:27.948976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:10:27.643238Z","time spent":"305.056861ms","remote":"127.0.0.1:42572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T22:10:27.972932Z","caller":"traceutil/trace.go:171","msg":"trace[803394404] linearizableReadLoop","detail":"{readStateIndex:2035; appliedIndex:2033; }","duration":"146.346522ms","start":"2026-05-24T22:10:27.826561Z","end":"2026-05-24T22:10:27.972907Z","steps":["trace[803394404] 'read index received' (duration: 121.429444ms)","trace[803394404] 'applied index is now lower than readState.Index' (duration: 24.915738ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:10:27.973041Z","caller":"traceutil/trace.go:171","msg":"trace[2028319643] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"329.735882ms","start":"2026-05-24T22:10:27.643268Z","end":"2026-05-24T22:10:27.973004Z","steps":["trace[2028319643] 'process raft request' (duration: 329.505016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:10:27.973352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.791835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-24T22:10:27.973359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:10:27.643242Z","time spent":"329.876266ms","remote":"127.0.0.1:42572","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-05-24T22:10:27.973419Z","caller":"traceutil/trace.go:171","msg":"trace[398498069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1965; }","duration":"146.912389ms","start":"2026-05-24T22:10:27.82649Z","end":"2026-05-24T22:10:27.973403Z","steps":["trace[398498069] 'agreement among raft nodes before linearized reading' (duration: 146.566079ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:10:28.081554Z","caller":"traceutil/trace.go:171","msg":"trace[1207731175] linearizableReadLoop","detail":"{readStateIndex:2036; appliedIndex:2035; }","duration":"101.752552ms","start":"2026-05-24T22:10:27.97977Z","end":"2026-05-24T22:10:28.081522Z","steps":["trace[1207731175] 'read index received' (duration: 14.347902ms)","trace[1207731175] 'applied index is now lower than readState.Index' (duration: 87.40361ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:10:28.081636Z","caller":"traceutil/trace.go:171","msg":"trace[313620019] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"104.083907ms","start":"2026-05-24T22:10:27.97752Z","end":"2026-05-24T22:10:28.081604Z","steps":["trace[313620019] 'process raft request' (duration: 16.646027ms)","trace[313620019] 'compare' (duration: 87.214954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T22:10:28.081789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.982348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:10:28.081902Z","caller":"traceutil/trace.go:171","msg":"trace[517819938] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1966; }","duration":"102.138313ms","start":"2026-05-24T22:10:27.979742Z","end":"2026-05-24T22:10:28.08188Z","steps":["trace[517819938] 'agreement among raft nodes before linearized reading' (duration: 101.922857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:10:31.473692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.53393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:10:31.473801Z","caller":"traceutil/trace.go:171","msg":"trace[1366418528] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1992; }","duration":"206.673014ms","start":"2026-05-24T22:10:31.2671Z","end":"2026-05-24T22:10:31.473773Z","steps":["trace[1366418528] 'count revisions from in-memory index tree' (duration: 206.452847ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:11:11.223543Z","caller":"traceutil/trace.go:171","msg":"trace[588307675] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2287; }","duration":"122.691811ms","start":"2026-05-24T22:11:11.100825Z","end":"2026-05-24T22:11:11.223517Z","steps":["trace[588307675] 'read index received' (duration: 122.63425ms)","trace[588307675] 'applied index is now lower than readState.Index' (duration: 56.381µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T22:11:11.223726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.881297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T22:11:11.223704Z","caller":"traceutil/trace.go:171","msg":"trace[1358020952] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"129.796931ms","start":"2026-05-24T22:11:11.093874Z","end":"2026-05-24T22:11:11.223671Z","steps":["trace[1358020952] 'process raft request' (duration: 129.502332ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:11:11.223771Z","caller":"traceutil/trace.go:171","msg":"trace[1732980455] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2209; }","duration":"122.98885ms","start":"2026-05-24T22:11:11.100772Z","end":"2026-05-24T22:11:11.223761Z","steps":["trace[1732980455] 'agreement among raft nodes before linearized reading' (duration: 122.854226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:11:45.77383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.22052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:11:45.773987Z","caller":"traceutil/trace.go:171","msg":"trace[2062592093] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2572; }","duration":"240.439536ms","start":"2026-05-24T22:11:45.533527Z","end":"2026-05-24T22:11:45.773967Z","steps":["trace[2062592093] 'count revisions from in-memory index tree' (duration: 240.135638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:11:45.774015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.855772ms","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-05-24T22:11:45.774105Z","caller":"traceutil/trace.go:171","msg":"trace[2020828806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2572; }","duration":"307.995366ms","start":"2026-05-24T22:11:45.466086Z","end":"2026-05-24T22:11:45.774082Z","steps":["trace[2020828806] 'range keys from in-memory index tree' (duration: 307.660106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:11:45.774124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.063162ms","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-05-24T22:11:45.774159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:11:45.466065Z","time spent":"308.083848ms","remote":"127.0.0.1:42572","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":"info","ts":"2026-05-24T22:11:45.774173Z","caller":"traceutil/trace.go:171","msg":"trace[44428861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2572; }","duration":"242.146104ms","start":"2026-05-24T22:11:45.532014Z","end":"2026-05-24T22:11:45.774161Z","steps":["trace[44428861] 'range keys from in-memory index tree' (duration: 241.926268ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:14:48.872344Z","caller":"traceutil/trace.go:171","msg":"trace[1390476935] linearizableReadLoop","detail":"{readStateIndex:3906; appliedIndex:3905; }","duration":"266.005477ms","start":"2026-05-24T22:14:48.606318Z","end":"2026-05-24T22:14:48.872324Z","steps":["trace[1390476935] 'read index received' (duration: 243.437903ms)","trace[1390476935] 'applied index is now lower than readState.Index' (duration: 22.566764ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:14:48.872431Z","caller":"traceutil/trace.go:171","msg":"trace[1391583929] transaction","detail":"{read_only:false; response_revision:3767; number_of_response:1; }","duration":"266.567483ms","start":"2026-05-24T22:14:48.605835Z","end":"2026-05-24T22:14:48.872402Z","steps":["trace[1391583929] 'process raft request' (duration: 243.943009ms)","trace[1391583929] 'compare' (duration: 22.359566ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T22:14:48.872495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.175131ms","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-05-24T22:14:48.872515Z","caller":"traceutil/trace.go:171","msg":"trace[1771871240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3767; }","duration":"266.252524ms","start":"2026-05-24T22:14:48.606257Z","end":"2026-05-24T22:14:48.87251Z","steps":["trace[1771871240] 'agreement among raft nodes before linearized reading' (duration: 266.138631ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:16:07.127499Z","caller":"traceutil/trace.go:171","msg":"trace[1704750883] linearizableReadLoop","detail":"{readStateIndex:4963; appliedIndex:4962; }","duration":"139.00662ms","start":"2026-05-24T22:16:06.988468Z","end":"2026-05-24T22:16:07.127474Z","steps":["trace[1704750883] 'read index received' (duration: 44.799677ms)","trace[1704750883] 'applied index is now lower than readState.Index' (duration: 94.205903ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:16:07.127553Z","caller":"traceutil/trace.go:171","msg":"trace[1620692334] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"151.897362ms","start":"2026-05-24T22:16:06.975626Z","end":"2026-05-24T22:16:07.127523Z","steps":["trace[1620692334] 'process raft request' (duration: 57.7652ms)","trace[1620692334] 'compare' (duration: 93.969207ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T22:16:07.12762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.129924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:16:07.127648Z","caller":"traceutil/trace.go:171","msg":"trace[352591580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4785; }","duration":"139.235047ms","start":"2026-05-24T22:16:06.988403Z","end":"2026-05-24T22:16:07.127638Z","steps":["trace[352591580] 'agreement among raft nodes before linearized reading' (duration: 139.166225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:16:12.120894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.27596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63016096-2bcb-439b-88ba-dff08081e84c\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-24T22:16:12.121023Z","caller":"traceutil/trace.go:171","msg":"trace[1503155224] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63016096-2bcb-439b-88ba-dff08081e84c; range_end:; response_count:1; response_revision:4951; }","duration":"225.446825ms","start":"2026-05-24T22:16:11.895545Z","end":"2026-05-24T22:16:12.120991Z","steps":["trace[1503155224] 'range keys from in-memory index tree' (duration: 225.078786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:16:12.121185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.567331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787092"} {"level":"info","ts":"2026-05-24T22:16:12.121261Z","caller":"traceutil/trace.go:171","msg":"trace[1235077146] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4951; }","duration":"147.700715ms","start":"2026-05-24T22:16:11.973542Z","end":"2026-05-24T22:16:12.121243Z","steps":["trace[1235077146] 'range keys from in-memory index tree' (duration: 147.173879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:16:12.121266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.19033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-05-24T22:16:12.121362Z","caller":"traceutil/trace.go:171","msg":"trace[1658554100] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4951; }","duration":"137.330434ms","start":"2026-05-24T22:16:11.98401Z","end":"2026-05-24T22:16:12.121341Z","steps":["trace[1658554100] 'range keys from in-memory index tree' (duration: 137.022235ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:16:24.967915Z","caller":"traceutil/trace.go:171","msg":"trace[1429915535] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"100.478235ms","start":"2026-05-24T22:16:24.867408Z","end":"2026-05-24T22:16:24.967886Z","steps":["trace[1429915535] 'process raft request' (duration: 100.28118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:16:25.265853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.828532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T22:16:25.265977Z","caller":"traceutil/trace.go:171","msg":"trace[1260967438] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"275.77583ms","start":"2026-05-24T22:16:24.990189Z","end":"2026-05-24T22:16:25.265965Z","steps":["trace[1260967438] 'process raft request' (duration: 40.66727ms)","trace[1260967438] 'compare' (duration: 234.74524ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:16:25.583166Z","caller":"traceutil/trace.go:171","msg":"trace[1450115154] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"103.667566ms","start":"2026-05-24T22:16:25.479477Z","end":"2026-05-24T22:16:25.583144Z","steps":["trace[1450115154] 'process raft request' (duration: 100.277051ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:18:05.624296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3030} {"level":"info","ts":"2026-05-24T22:18:05.706625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3030,"took":"80.704304ms","hash":1792340895,"current-db-size-bytes":59232256,"current-db-size":"59 MB","current-db-size-in-use-bytes":32231424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-24T22:18:05.706704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1792340895,"revision":3030,"compact-revision":-1} {"level":"info","ts":"2026-05-24T22:22:33.715172Z","caller":"traceutil/trace.go:171","msg":"trace[494966434] linearizableReadLoop","detail":"{readStateIndex:8439; appliedIndex:8438; }","duration":"303.013252ms","start":"2026-05-24T22:22:33.41214Z","end":"2026-05-24T22:22:33.715154Z","steps":["trace[494966434] 'read index received' (duration: 302.911419ms)","trace[494966434] 'applied index is now lower than readState.Index' (duration: 101.003µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:22:33.715237Z","caller":"traceutil/trace.go:171","msg":"trace[687043851] transaction","detail":"{read_only:false; response_revision:8158; number_of_response:1; }","duration":"312.359914ms","start":"2026-05-24T22:22:33.402846Z","end":"2026-05-24T22:22:33.715206Z","steps":["trace[687043851] 'process raft request' (duration: 312.19822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:33.71539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:22:33.402826Z","time spent":"312.490147ms","remote":"127.0.0.1:42572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T22:22:33.715391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.227809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-24T22:22:33.715454Z","caller":"traceutil/trace.go:171","msg":"trace[706851016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8158; }","duration":"303.340242ms","start":"2026-05-24T22:22:33.412097Z","end":"2026-05-24T22:22:33.715437Z","steps":["trace[706851016] 'agreement among raft nodes before linearized reading' (duration: 303.141816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:33.715494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T22:22:33.412077Z","time spent":"303.411533ms","remote":"127.0.0.1:42572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-24T22:22:33.839225Z","caller":"traceutil/trace.go:171","msg":"trace[685137028] linearizableReadLoop","detail":"{readStateIndex:8440; appliedIndex:8439; }","duration":"123.979948ms","start":"2026-05-24T22:22:33.715226Z","end":"2026-05-24T22:22:33.839205Z","steps":["trace[685137028] 'read index received' (duration: 107.332181ms)","trace[685137028] 'applied index is now lower than readState.Index' (duration: 16.647017ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:22:33.83929Z","caller":"traceutil/trace.go:171","msg":"trace[1666520874] transaction","detail":"{read_only:false; response_revision:8159; number_of_response:1; }","duration":"175.00297ms","start":"2026-05-24T22:22:33.664279Z","end":"2026-05-24T22:22:33.839282Z","steps":["trace[1666520874] 'process raft request' (duration: 158.132607ms)","trace[1666520874] 'compare' (duration: 16.359419ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:22:33.839505Z","caller":"traceutil/trace.go:171","msg":"trace[1199380087] transaction","detail":"{read_only:false; response_revision:8160; number_of_response:1; }","duration":"121.114538ms","start":"2026-05-24T22:22:33.718385Z","end":"2026-05-24T22:22:33.8395Z","steps":["trace[1199380087] 'process raft request' (duration: 120.729687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:33.842136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.851266ms","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-05-24T22:22:33.84221Z","caller":"traceutil/trace.go:171","msg":"trace[1428284191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8160; }","duration":"215.958709ms","start":"2026-05-24T22:22:33.626235Z","end":"2026-05-24T22:22:33.842194Z","steps":["trace[1428284191] 'agreement among raft nodes before linearized reading' (duration: 213.15913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:33.842215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.424429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-24T22:22:33.842251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.321916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-24T22:22:33.842337Z","caller":"traceutil/trace.go:171","msg":"trace[1049909066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8160; }","duration":"192.544323ms","start":"2026-05-24T22:22:33.649755Z","end":"2026-05-24T22:22:33.842299Z","steps":["trace[1049909066] 'agreement among raft nodes before linearized reading' (duration: 189.635421ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:22:33.84237Z","caller":"traceutil/trace.go:171","msg":"trace[816387046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8160; }","duration":"161.4499ms","start":"2026-05-24T22:22:33.680894Z","end":"2026-05-24T22:22:33.842344Z","steps":["trace[816387046] 'agreement among raft nodes before linearized reading' (duration: 158.491787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:33.842536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.858832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T22:22:33.842648Z","caller":"traceutil/trace.go:171","msg":"trace[814625792] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:8160; }","duration":"223.032618ms","start":"2026-05-24T22:22:33.619596Z","end":"2026-05-24T22:22:33.842628Z","steps":["trace[814625792] 'agreement among raft nodes before linearized reading' (duration: 219.671724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:33.842578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.715741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-24T22:22:33.84271Z","caller":"traceutil/trace.go:171","msg":"trace[1338193347] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8160; }","duration":"159.899976ms","start":"2026-05-24T22:22:33.6828Z","end":"2026-05-24T22:22:33.8427Z","steps":["trace[1338193347] 'agreement among raft nodes before linearized reading' (duration: 156.580133ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:22:45.877378Z","caller":"traceutil/trace.go:171","msg":"trace[1871150790] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"102.355151ms","start":"2026-05-24T22:22:45.774987Z","end":"2026-05-24T22:22:45.877342Z","steps":["trace[1871150790] 'process raft request' (duration: 102.237998ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:22:46.750446Z","caller":"traceutil/trace.go:171","msg":"trace[949109052] linearizableReadLoop","detail":"{readStateIndex:8512; appliedIndex:8511; }","duration":"100.028677ms","start":"2026-05-24T22:22:46.650398Z","end":"2026-05-24T22:22:46.750427Z","steps":["trace[949109052] 'read index received' (duration: 99.861952ms)","trace[949109052] 'applied index is now lower than readState.Index' (duration: 166.085µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:22:46.750826Z","caller":"traceutil/trace.go:171","msg":"trace[2075201996] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"123.31909ms","start":"2026-05-24T22:22:46.627496Z","end":"2026-05-24T22:22:46.750815Z","steps":["trace[2075201996] 'process raft request' (duration: 122.818307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:46.751056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.635373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:22:46.751089Z","caller":"traceutil/trace.go:171","msg":"trace[438340169] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8227; }","duration":"100.716476ms","start":"2026-05-24T22:22:46.650365Z","end":"2026-05-24T22:22:46.751081Z","steps":["trace[438340169] 'agreement among raft nodes before linearized reading' (duration: 100.638724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:46.751264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.806869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:22:46.751288Z","caller":"traceutil/trace.go:171","msg":"trace[612525613] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8227; }","duration":"100.846979ms","start":"2026-05-24T22:22:46.650436Z","end":"2026-05-24T22:22:46.751283Z","steps":["trace[612525613] 'agreement among raft nodes before linearized reading' (duration: 100.807958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:22:46.751905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.659765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:22:46.751935Z","caller":"traceutil/trace.go:171","msg":"trace[1279048989] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8227; }","duration":"100.691515ms","start":"2026-05-24T22:22:46.651237Z","end":"2026-05-24T22:22:46.751929Z","steps":["trace[1279048989] 'agreement among raft nodes before linearized reading' (duration: 100.646894ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:23:05.631374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6162} {"level":"info","ts":"2026-05-24T22:23:05.726346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6162,"took":"92.882887ms","hash":3321140054,"current-db-size-bytes":59232256,"current-db-size":"59 MB","current-db-size-in-use-bytes":23203840,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-24T22:23:05.726422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3321140054,"revision":6162,"compact-revision":3030} {"level":"warn","ts":"2026-05-24T22:23:26.334062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.906149ms","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-05-24T22:23:26.334164Z","caller":"traceutil/trace.go:171","msg":"trace[2063199959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8534; }","duration":"123.055583ms","start":"2026-05-24T22:23:26.211089Z","end":"2026-05-24T22:23:26.334144Z","steps":["trace[2063199959] 'range keys from in-memory index tree' (duration: 122.745184ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:23:26.473905Z","caller":"traceutil/trace.go:171","msg":"trace[19547243] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"136.335265ms","start":"2026-05-24T22:23:26.337526Z","end":"2026-05-24T22:23:26.473861Z","steps":["trace[19547243] 'process raft request' (duration: 117.295511ms)","trace[19547243] 'compare' (duration: 18.836598ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:23:26.617179Z","caller":"traceutil/trace.go:171","msg":"trace[2113263229] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"277.505926ms","start":"2026-05-24T22:23:26.339647Z","end":"2026-05-24T22:23:26.617153Z","steps":["trace[2113263229] 'process raft request' (duration: 271.788256ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:23:26.617551Z","caller":"traceutil/trace.go:171","msg":"trace[210169078] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"277.779493ms","start":"2026-05-24T22:23:26.339752Z","end":"2026-05-24T22:23:26.617531Z","steps":["trace[210169078] 'process raft request' (duration: 277.31036ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:23:26.617556Z","caller":"traceutil/trace.go:171","msg":"trace[82078973] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"275.056767ms","start":"2026-05-24T22:23:26.342484Z","end":"2026-05-24T22:23:26.617541Z","steps":["trace[82078973] 'process raft request' (duration: 274.843291ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:23:26.617608Z","caller":"traceutil/trace.go:171","msg":"trace[1706808370] linearizableReadLoop","detail":"{readStateIndex:8840; appliedIndex:8834; }","duration":"227.948035ms","start":"2026-05-24T22:23:26.389635Z","end":"2026-05-24T22:23:26.617583Z","steps":["trace[1706808370] 'read index received' (duration: 65.176568ms)","trace[1706808370] 'applied index is now lower than readState.Index' (duration: 162.770797ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:23:26.617752Z","caller":"traceutil/trace.go:171","msg":"trace[765992662] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"274.581163ms","start":"2026-05-24T22:23:26.343153Z","end":"2026-05-24T22:23:26.617734Z","steps":["trace[765992662] 'process raft request' (duration: 274.313955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:23:26.6179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.245533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-24T22:23:26.617973Z","caller":"traceutil/trace.go:171","msg":"trace[533780876] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"232.120812ms","start":"2026-05-24T22:23:26.385827Z","end":"2026-05-24T22:23:26.617948Z","steps":["trace[533780876] 'process raft request' (duration: 231.70775ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:23:26.617964Z","caller":"traceutil/trace.go:171","msg":"trace[715145426] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:8542; }","duration":"228.357126ms","start":"2026-05-24T22:23:26.38959Z","end":"2026-05-24T22:23:26.617947Z","steps":["trace[715145426] 'agreement among raft nodes before linearized reading' (duration: 228.109449ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:23:26.618004Z","caller":"traceutil/trace.go:171","msg":"trace[2109879822] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"227.260896ms","start":"2026-05-24T22:23:26.390719Z","end":"2026-05-24T22:23:26.617979Z","steps":["trace[2109879822] 'process raft request' (duration: 226.839434ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:23:48.087989Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000237b.wal"} {"level":"info","ts":"2026-05-24T22:24:41.860803Z","caller":"traceutil/trace.go:171","msg":"trace[697198105] transaction","detail":"{read_only:false; response_revision:9283; number_of_response:1; }","duration":"182.254399ms","start":"2026-05-24T22:24:41.678521Z","end":"2026-05-24T22:24:41.860775Z","steps":["trace[697198105] 'process raft request' (duration: 182.071394ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:25:35.335148Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-24T22:25:35.341519Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-24T22:25:35.341606Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-24T22:28:05.639652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8332} {"level":"info","ts":"2026-05-24T22:28:05.717159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8332,"took":"74.692431ms","hash":826063406,"current-db-size-bytes":59232256,"current-db-size":"59 MB","current-db-size-in-use-bytes":26632192,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-24T22:28:05.717216Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":826063406,"revision":8332,"compact-revision":6162} {"level":"info","ts":"2026-05-24T22:28:25.353019Z","caller":"traceutil/trace.go:171","msg":"trace[1716646996] linearizableReadLoop","detail":"{readStateIndex:11495; appliedIndex:11494; }","duration":"122.658577ms","start":"2026-05-24T22:28:25.230331Z","end":"2026-05-24T22:28:25.35299Z","steps":["trace[1716646996] 'read index received' (duration: 122.454171ms)","trace[1716646996] 'applied index is now lower than readState.Index' (duration: 203.326µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:28:25.353132Z","caller":"traceutil/trace.go:171","msg":"trace[27991954] transaction","detail":"{read_only:false; response_revision:11120; number_of_response:1; }","duration":"139.696078ms","start":"2026-05-24T22:28:25.213388Z","end":"2026-05-24T22:28:25.353085Z","steps":["trace[27991954] 'process raft request' (duration: 139.437671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:28:25.353261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.904633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-24T22:28:25.353309Z","caller":"traceutil/trace.go:171","msg":"trace[28163117] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11120; }","duration":"123.007626ms","start":"2026-05-24T22:28:25.230289Z","end":"2026-05-24T22:28:25.353297Z","steps":["trace[28163117] 'agreement among raft nodes before linearized reading' (duration: 122.833101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:28:25.360939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.407171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:28:25.360978Z","caller":"traceutil/trace.go:171","msg":"trace[1570506825] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11121; }","duration":"130.469223ms","start":"2026-05-24T22:28:25.2305Z","end":"2026-05-24T22:28:25.360969Z","steps":["trace[1570506825] 'agreement among raft nodes before linearized reading' (duration: 130.346489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:28:25.361238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.198696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T22:28:25.361287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.173355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:28:25.361373Z","caller":"traceutil/trace.go:171","msg":"trace[21210926] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11121; }","duration":"130.328649ms","start":"2026-05-24T22:28:25.231028Z","end":"2026-05-24T22:28:25.361356Z","steps":["trace[21210926] 'agreement among raft nodes before linearized reading' (duration: 130.189315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T22:28:25.361287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.74391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:28:25.3613Z","caller":"traceutil/trace.go:171","msg":"trace[517374853] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11121; }","duration":"130.304418ms","start":"2026-05-24T22:28:25.230981Z","end":"2026-05-24T22:28:25.361286Z","steps":["trace[517374853] 'agreement among raft nodes before linearized reading' (duration: 130.216756ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:28:25.361497Z","caller":"traceutil/trace.go:171","msg":"trace[787633091] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11121; }","duration":"130.945186ms","start":"2026-05-24T22:28:25.230523Z","end":"2026-05-24T22:28:25.361468Z","steps":["trace[787633091] 'agreement among raft nodes before linearized reading' (duration: 130.72402ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:28:26.011063Z","caller":"traceutil/trace.go:171","msg":"trace[551913029] transaction","detail":"{read_only:false; response_revision:11127; number_of_response:1; }","duration":"109.679458ms","start":"2026-05-24T22:28:25.90136Z","end":"2026-05-24T22:28:26.011039Z","steps":["trace[551913029] 'process raft request' (duration: 88.687397ms)","trace[551913029] 'compare' (duration: 20.794645ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:28:26.011264Z","caller":"traceutil/trace.go:171","msg":"trace[994660617] transaction","detail":"{read_only:false; response_revision:11128; number_of_response:1; }","duration":"107.644351ms","start":"2026-05-24T22:28:25.903583Z","end":"2026-05-24T22:28:26.011228Z","steps":["trace[994660617] 'process raft request' (duration: 107.399554ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:33:05.644444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11011} {"level":"info","ts":"2026-05-24T22:33:05.721741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11011,"took":"75.233724ms","hash":3037698358,"current-db-size-bytes":59232256,"current-db-size":"59 MB","current-db-size-in-use-bytes":28401664,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-24T22:33:05.721811Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3037698358,"revision":11011,"compact-revision":8332} {"level":"info","ts":"2026-05-24T22:34:41.980119Z","caller":"traceutil/trace.go:171","msg":"trace[370933878] transaction","detail":"{read_only:false; response_revision:13659; number_of_response:1; }","duration":"132.460517ms","start":"2026-05-24T22:34:41.847603Z","end":"2026-05-24T22:34:41.980064Z","steps":["trace[370933878] 'process raft request' (duration: 132.198979ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:34:42.084316Z","caller":"traceutil/trace.go:171","msg":"trace[1149302643] transaction","detail":"{read_only:false; response_revision:13660; number_of_response:1; }","duration":"234.519483ms","start":"2026-05-24T22:34:41.849768Z","end":"2026-05-24T22:34:42.084287Z","steps":["trace[1149302643] 'process raft request' (duration: 197.452215ms)","trace[1149302643] 'compare' (duration: 36.592804ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:34:57.466838Z","caller":"traceutil/trace.go:171","msg":"trace[449753348] transaction","detail":"{read_only:false; response_revision:13752; number_of_response:1; }","duration":"130.571344ms","start":"2026-05-24T22:34:57.33624Z","end":"2026-05-24T22:34:57.466812Z","steps":["trace[449753348] 'process raft request' (duration: 130.226395ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:34:57.557714Z","caller":"traceutil/trace.go:171","msg":"trace[1198826133] linearizableReadLoop","detail":"{readStateIndex:14220; appliedIndex:14218; }","duration":"110.79504ms","start":"2026-05-24T22:34:57.446884Z","end":"2026-05-24T22:34:57.557679Z","steps":["trace[1198826133] 'read index received' (duration: 19.801264ms)","trace[1198826133] 'applied index is now lower than readState.Index' (duration: 90.992336ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T22:34:57.557837Z","caller":"traceutil/trace.go:171","msg":"trace[1073336707] transaction","detail":"{read_only:false; response_revision:13753; number_of_response:1; }","duration":"181.684915ms","start":"2026-05-24T22:34:57.376098Z","end":"2026-05-24T22:34:57.557783Z","steps":["trace[1073336707] 'process raft request' (duration: 151.432508ms)","trace[1073336707] 'compare' (duration: 29.984809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T22:34:57.557897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.986626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T22:34:57.557944Z","caller":"traceutil/trace.go:171","msg":"trace[2038397366] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:13753; }","duration":"111.13014ms","start":"2026-05-24T22:34:57.446797Z","end":"2026-05-24T22:34:57.557927Z","steps":["trace[2038397366] 'agreement among raft nodes before linearized reading' (duration: 111.006996ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:35:19.829572Z","caller":"traceutil/trace.go:171","msg":"trace[1046855674] transaction","detail":"{read_only:false; response_revision:14062; number_of_response:1; }","duration":"262.424974ms","start":"2026-05-24T22:35:19.567114Z","end":"2026-05-24T22:35:19.829539Z","steps":["trace[1046855674] 'process raft request' (duration: 262.238129ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:35:19.833845Z","caller":"traceutil/trace.go:171","msg":"trace[11483118] transaction","detail":"{read_only:false; response_revision:14063; number_of_response:1; }","duration":"165.277665ms","start":"2026-05-24T22:35:19.66855Z","end":"2026-05-24T22:35:19.833827Z","steps":["trace[11483118] 'process raft request' (duration: 164.906335ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:36:30.10531Z","caller":"traceutil/trace.go:171","msg":"trace[1520546468] transaction","detail":"{read_only:false; response_revision:14787; number_of_response:1; }","duration":"102.186019ms","start":"2026-05-24T22:36:30.003098Z","end":"2026-05-24T22:36:30.105284Z","steps":["trace[1520546468] 'process raft request' (duration: 101.939632ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T22:38:05.650571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13051} {"level":"info","ts":"2026-05-24T22:38:05.723069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13051,"took":"69.939737ms","hash":4168279040,"current-db-size-bytes":59232256,"current-db-size":"59 MB","current-db-size-in-use-bytes":30879744,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-24T22:38:05.723128Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4168279040,"revision":13051,"compact-revision":11011} {"level":"info","ts":"2026-05-24T22:43:05.660567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15407} {"level":"info","ts":"2026-05-24T22:43:05.754386Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15407,"took":"90.820542ms","hash":816708580,"current-db-size-bytes":59232256,"current-db-size":"59 MB","current-db-size-in-use-bytes":31199232,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-24T22:43:05.754483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":816708580,"revision":15407,"compact-revision":13051} {"level":"info","ts":"2026-05-24T22:48:05.666334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17601} {"level":"info","ts":"2026-05-24T22:48:05.755285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17601,"took":"86.364986ms","hash":3382338941,"current-db-size-bytes":59232256,"current-db-size":"59 MB","current-db-size-in-use-bytes":25268224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-24T22:48:05.755348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3382338941,"revision":17601,"compact-revision":15407} {"level":"info","ts":"2026-05-24T22:48:40.001826Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-24T22:48:40.006434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-24T22:48:40.006523Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}