{"level":"warn","ts":"2026-04-17T18:44:21.220035Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T18:44:21.220123Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.116:2380","--initial-cluster=instance=https://199.19.213.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.116: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-04-17T18:44:21.220202Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T18:44:21.220219Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.116:2380"]} {"level":"info","ts":"2026-04-17T18:44:21.220246Z","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-04-17T18:44:21.220843Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.116:2379"]} {"level":"info","ts":"2026-04-17T18:44:21.220909Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.116:2380"],"listen-peer-urls":["https://199.19.213.116:2380"],"advertise-client-urls":["https://199.19.213.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.116: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-04-17T18:44:21.226256Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.166792ms"} {"level":"info","ts":"2026-04-17T18:44:21.232778Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca739d563b259b56","cluster-id":"2052ec4484eed829"} {"level":"info","ts":"2026-04-17T18:44:21.232849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T18:44:21.232872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became follower at term 0"} {"level":"info","ts":"2026-04-17T18:44:21.232886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca739d563b259b56 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T18:44:21.232895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became follower at term 1"} {"level":"info","ts":"2026-04-17T18:44:21.232937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 switched to configuration voters=(14588176611668237142)"} {"level":"warn","ts":"2026-04-17T18:44:21.23959Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T18:44:21.241041Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T18:44:21.242387Z","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-04-17T18:44:21.244615Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca739d563b259b56","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T18:44:21.244786Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca739d563b259b56","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T18:44:21.244993Z","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-04-17T18:44:21.245192Z","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-04-17T18:44:21.245217Z","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-04-17T18:44:21.246237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 switched to configuration voters=(14588176611668237142)"} {"level":"info","ts":"2026-04-17T18:44:21.246846Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2052ec4484eed829","local-member-id":"ca739d563b259b56","added-peer-id":"ca739d563b259b56","added-peer-peer-urls":["https://199.19.213.116:2380"]} {"level":"info","ts":"2026-04-17T18:44:21.249262Z","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-04-17T18:44:21.249436Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.116:2380"} {"level":"info","ts":"2026-04-17T18:44:21.249536Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.116:2380"} {"level":"info","ts":"2026-04-17T18:44:21.249684Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca739d563b259b56","initial-advertise-peer-urls":["https://199.19.213.116:2380"],"listen-peer-urls":["https://199.19.213.116:2380"],"advertise-client-urls":["https://199.19.213.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T18:44:21.249731Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T18:44:21.333156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T18:44:21.333226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T18:44:21.333258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 received MsgPreVoteResp from ca739d563b259b56 at term 1"} {"level":"info","ts":"2026-04-17T18:44:21.333278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became candidate at term 2"} {"level":"info","ts":"2026-04-17T18:44:21.33329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 received MsgVoteResp from ca739d563b259b56 at term 2"} {"level":"info","ts":"2026-04-17T18:44:21.333306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became leader at term 2"} {"level":"info","ts":"2026-04-17T18:44:21.33332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca739d563b259b56 elected leader ca739d563b259b56 at term 2"} {"level":"info","ts":"2026-04-17T18:44:21.334518Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca739d563b259b56","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.116:2379]}","request-path":"/0/members/ca739d563b259b56/attributes","cluster-id":"2052ec4484eed829","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T18:44:21.334531Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:44:21.334693Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:44:21.334757Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:44:21.334834Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T18:44:21.335021Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T18:44:21.335395Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2052ec4484eed829","local-member-id":"ca739d563b259b56","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:44:21.335502Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:44:21.335538Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:44:21.338245Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T18:44:21.338359Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.116:2379"} {"level":"info","ts":"2026-04-17T18:44:36.29792Z","caller":"traceutil/trace.go:171","msg":"trace[1683371234] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"115.859329ms","start":"2026-04-17T18:44:36.182036Z","end":"2026-04-17T18:44:36.297895Z","steps":["trace[1683371234] 'process raft request' (duration: 115.714265ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:36.740185Z","caller":"traceutil/trace.go:171","msg":"trace[169050526] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"155.73488ms","start":"2026-04-17T18:44:36.584426Z","end":"2026-04-17T18:44:36.740161Z","steps":["trace[169050526] 'process raft request' (duration: 155.600516ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:36.794217Z","caller":"traceutil/trace.go:171","msg":"trace[1219178252] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"193.313508ms","start":"2026-04-17T18:44:36.600878Z","end":"2026-04-17T18:44:36.794191Z","steps":["trace[1219178252] 'process raft request' (duration: 192.950808ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:50.565843Z","caller":"traceutil/trace.go:171","msg":"trace[1135800712] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"118.134712ms","start":"2026-04-17T18:44:50.447678Z","end":"2026-04-17T18:44:50.565813Z","steps":["trace[1135800712] 'process raft request' (duration: 108.728204ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:50.56625Z","caller":"traceutil/trace.go:171","msg":"trace[28367345] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"117.384191ms","start":"2026-04-17T18:44:50.448832Z","end":"2026-04-17T18:44:50.566217Z","steps":["trace[28367345] 'process raft request' (duration: 117.120113ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:53.336174Z","caller":"traceutil/trace.go:171","msg":"trace[1211043723] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"199.292502ms","start":"2026-04-17T18:44:53.136841Z","end":"2026-04-17T18:44:53.336134Z","steps":["trace[1211043723] 'process raft request' (duration: 199.017874ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:56.529902Z","caller":"traceutil/trace.go:171","msg":"trace[1664681155] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"108.038501ms","start":"2026-04-17T18:44:56.421835Z","end":"2026-04-17T18:44:56.529874Z","steps":["trace[1664681155] 'process raft request' (duration: 44.736393ms)","trace[1664681155] 'compare' (duration: 63.160954ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:44:57.214969Z","caller":"traceutil/trace.go:171","msg":"trace[1302524823] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"383.064943ms","start":"2026-04-17T18:44:56.831874Z","end":"2026-04-17T18:44:57.214939Z","steps":["trace[1302524823] 'read index received' (duration: 382.362604ms)","trace[1302524823] 'applied index is now lower than readState.Index' (duration: 701.239µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:44:57.215025Z","caller":"traceutil/trace.go:171","msg":"trace[1732661553] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"567.218459ms","start":"2026-04-17T18:44:56.647773Z","end":"2026-04-17T18:44:57.214991Z","steps":["trace[1732661553] 'process raft request' (duration: 566.537421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:57.215282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.387871ms","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-04-17T18:44:57.215365Z","caller":"traceutil/trace.go:171","msg":"trace[1063746715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"383.490404ms","start":"2026-04-17T18:44:56.83186Z","end":"2026-04-17T18:44:57.21535Z","steps":["trace[1063746715] 'agreement among raft nodes before linearized reading' (duration: 383.214257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:57.215426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:56.831796Z","time spent":"383.620398ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:44:57.215808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:44:56.647732Z","time spent":"567.390784ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:44:57.240834Z","caller":"traceutil/trace.go:171","msg":"trace[362863416] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"290.096871ms","start":"2026-04-17T18:44:56.950715Z","end":"2026-04-17T18:44:57.240812Z","steps":["trace[362863416] 'process raft request' (duration: 289.999338ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:57.240808Z","caller":"traceutil/trace.go:171","msg":"trace[1148514586] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"290.960514ms","start":"2026-04-17T18:44:56.949812Z","end":"2026-04-17T18:44:57.240773Z","steps":["trace[1148514586] 'process raft request' (duration: 290.681736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:57.240876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.604523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:44:57.24098Z","caller":"traceutil/trace.go:171","msg":"trace[273793627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"214.762127ms","start":"2026-04-17T18:44:57.02619Z","end":"2026-04-17T18:44:57.240952Z","steps":["trace[273793627] 'agreement among raft nodes before linearized reading' (duration: 214.574272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:58.631611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.894619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:44:58.631789Z","caller":"traceutil/trace.go:171","msg":"trace[430095264] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"141.664483ms","start":"2026-04-17T18:44:58.490102Z","end":"2026-04-17T18:44:58.631767Z","steps":["trace[430095264] 'process raft request' (duration: 16.367994ms)","trace[430095264] 'compare' (duration: 124.756834ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:44:58.634662Z","caller":"traceutil/trace.go:171","msg":"trace[911737060] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"101.570866ms","start":"2026-04-17T18:44:58.533064Z","end":"2026-04-17T18:44:58.634635Z","steps":["trace[911737060] 'read index received' (duration: 101.35789ms)","trace[911737060] 'applied index is now lower than readState.Index' (duration: 211.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:44:58.634704Z","caller":"traceutil/trace.go:171","msg":"trace[1484939572] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"142.381443ms","start":"2026-04-17T18:44:58.4923Z","end":"2026-04-17T18:44:58.634682Z","steps":["trace[1484939572] 'process raft request' (duration: 142.202638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:44:58.634931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.809502ms","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-04-17T18:44:58.634983Z","caller":"traceutil/trace.go:171","msg":"trace[2111801903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"101.985577ms","start":"2026-04-17T18:44:58.532984Z","end":"2026-04-17T18:44:58.63497Z","steps":["trace[2111801903] 'agreement among raft nodes before linearized reading' (duration: 101.762331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:12.146958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.591941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:45:12.147056Z","caller":"traceutil/trace.go:171","msg":"trace[173471685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"120.741475ms","start":"2026-04-17T18:45:12.026294Z","end":"2026-04-17T18:45:12.147036Z","steps":["trace[173471685] 'range keys from in-memory index tree' (duration: 120.503219ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:38.981434Z","caller":"traceutil/trace.go:171","msg":"trace[1583928569] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"323.116236ms","start":"2026-04-17T18:45:38.658284Z","end":"2026-04-17T18:45:38.9814Z","steps":["trace[1583928569] 'process raft request' (duration: 321.075271ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:38.980477Z","caller":"traceutil/trace.go:171","msg":"trace[507914295] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"123.562432ms","start":"2026-04-17T18:45:38.856863Z","end":"2026-04-17T18:45:38.980425Z","steps":["trace[507914295] 'read index received' (duration: 122.486782ms)","trace[507914295] 'applied index is now lower than readState.Index' (duration: 1.07322ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:38.98172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.865087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-17T18:45:38.982243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:38.658252Z","time spent":"323.894968ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:45:38.982265Z","caller":"traceutil/trace.go:171","msg":"trace[1639422171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"125.525026ms","start":"2026-04-17T18:45:38.856714Z","end":"2026-04-17T18:45:38.982239Z","steps":["trace[1639422171] 'agreement among raft nodes before linearized reading' (duration: 124.834807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:22.93143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.28126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6fa4c820-a247-4b74-a62a-5bc58278e3e3\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-17T18:46:22.931538Z","caller":"traceutil/trace.go:171","msg":"trace[309834739] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6fa4c820-a247-4b74-a62a-5bc58278e3e3; range_end:; response_count:1; response_revision:1782; }","duration":"122.462544ms","start":"2026-04-17T18:46:22.80905Z","end":"2026-04-17T18:46:22.931512Z","steps":["trace[309834739] 'range keys from in-memory index tree' (duration: 122.054893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:22.931551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.180478ms","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-04-17T18:46:22.93169Z","caller":"traceutil/trace.go:171","msg":"trace[184965492] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1782; }","duration":"105.345232ms","start":"2026-04-17T18:46:22.826319Z","end":"2026-04-17T18:46:22.931664Z","steps":["trace[184965492] 'range keys from in-memory index tree' (duration: 104.925882ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:23.225101Z","caller":"traceutil/trace.go:171","msg":"trace[610935311] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"286.380317ms","start":"2026-04-17T18:46:22.938688Z","end":"2026-04-17T18:46:23.225068Z","steps":["trace[610935311] 'process raft request' (duration: 286.007217ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:23.364441Z","caller":"traceutil/trace.go:171","msg":"trace[2098838693] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"134.283863ms","start":"2026-04-17T18:46:23.230133Z","end":"2026-04-17T18:46:23.364417Z","steps":["trace[2098838693] 'process raft request' (duration: 134.084987ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:23.364671Z","caller":"traceutil/trace.go:171","msg":"trace[1725313763] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"127.674264ms","start":"2026-04-17T18:46:23.23698Z","end":"2026-04-17T18:46:23.364654Z","steps":["trace[1725313763] 'process raft request' (duration: 127.375066ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:23.606085Z","caller":"traceutil/trace.go:171","msg":"trace[469974544] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"235.460243ms","start":"2026-04-17T18:46:23.370597Z","end":"2026-04-17T18:46:23.606057Z","steps":["trace[469974544] 'process raft request' (duration: 235.293838ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:23.644732Z","caller":"traceutil/trace.go:171","msg":"trace[2054757388] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"274.023353ms","start":"2026-04-17T18:46:23.370683Z","end":"2026-04-17T18:46:23.644707Z","steps":["trace[2054757388] 'process raft request' (duration: 273.846479ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:23.645085Z","caller":"traceutil/trace.go:171","msg":"trace[1530638238] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"274.26251ms","start":"2026-04-17T18:46:23.37081Z","end":"2026-04-17T18:46:23.645072Z","steps":["trace[1530638238] 'process raft request' (duration: 273.859279ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:23.64531Z","caller":"traceutil/trace.go:171","msg":"trace[57995532] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"273.683444ms","start":"2026-04-17T18:46:23.371453Z","end":"2026-04-17T18:46:23.645137Z","steps":["trace[57995532] 'process raft request' (duration: 273.445958ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:35.9984Z","caller":"traceutil/trace.go:171","msg":"trace[1452517129] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"164.817157ms","start":"2026-04-17T18:46:35.833557Z","end":"2026-04-17T18:46:35.998374Z","steps":["trace[1452517129] 'read index received' (duration: 164.637452ms)","trace[1452517129] 'applied index is now lower than readState.Index' (duration: 178.025µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:46:35.998512Z","caller":"traceutil/trace.go:171","msg":"trace[1018631691] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"231.24321ms","start":"2026-04-17T18:46:35.767241Z","end":"2026-04-17T18:46:35.998484Z","steps":["trace[1018631691] 'process raft request' (duration: 230.945642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:35.998648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.070484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T18:46:35.998686Z","caller":"traceutil/trace.go:171","msg":"trace[155302861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1895; }","duration":"165.147866ms","start":"2026-04-17T18:46:35.833527Z","end":"2026-04-17T18:46:35.998674Z","steps":["trace[155302861] 'agreement among raft nodes before linearized reading' (duration: 164.984141ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:36.004042Z","caller":"traceutil/trace.go:171","msg":"trace[1410023741] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"111.722214ms","start":"2026-04-17T18:46:35.892308Z","end":"2026-04-17T18:46:36.004031Z","steps":["trace[1410023741] 'process raft request' (duration: 111.476478ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:39.617689Z","caller":"traceutil/trace.go:171","msg":"trace[429983677] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"180.316076ms","start":"2026-04-17T18:46:39.437336Z","end":"2026-04-17T18:46:39.617653Z","steps":["trace[429983677] 'read index received' (duration: 179.926615ms)","trace[429983677] 'applied index is now lower than readState.Index' (duration: 387.82µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:46:39.617817Z","caller":"traceutil/trace.go:171","msg":"trace[2022358533] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"215.71283ms","start":"2026-04-17T18:46:39.402069Z","end":"2026-04-17T18:46:39.617782Z","steps":["trace[2022358533] 'process raft request' (duration: 215.34171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:39.617934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.576962ms","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-04-17T18:46:39.617975Z","caller":"traceutil/trace.go:171","msg":"trace[1598640886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1922; }","duration":"180.704326ms","start":"2026-04-17T18:46:39.437259Z","end":"2026-04-17T18:46:39.617963Z","steps":["trace[1598640886] 'agreement among raft nodes before linearized reading' (duration: 180.514731ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:12.327793Z","caller":"traceutil/trace.go:171","msg":"trace[1400515950] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"103.599506ms","start":"2026-04-17T18:47:12.224162Z","end":"2026-04-17T18:47:12.327761Z","steps":["trace[1400515950] 'process raft request' (duration: 88.55874ms)","trace[1400515950] 'compare' (duration: 14.892282ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:12.354037Z","caller":"traceutil/trace.go:171","msg":"trace[1744139264] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"129.480574ms","start":"2026-04-17T18:47:12.224501Z","end":"2026-04-17T18:47:12.353981Z","steps":["trace[1744139264] 'process raft request' (duration: 129.081173ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:12.354727Z","caller":"traceutil/trace.go:171","msg":"trace[579522466] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"129.941286ms","start":"2026-04-17T18:47:12.224749Z","end":"2026-04-17T18:47:12.354691Z","steps":["trace[579522466] 'process raft request' (duration: 128.922509ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:12.354879Z","caller":"traceutil/trace.go:171","msg":"trace[299341913] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"127.766217ms","start":"2026-04-17T18:47:12.226989Z","end":"2026-04-17T18:47:12.354755Z","steps":["trace[299341913] 'process raft request' (duration: 127.591542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:18.646552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.223882ms","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-04-17T18:47:18.64665Z","caller":"traceutil/trace.go:171","msg":"trace[859778787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2134; }","duration":"293.385547ms","start":"2026-04-17T18:47:18.353241Z","end":"2026-04-17T18:47:18.646627Z","steps":["trace[859778787] 'range keys from in-memory index tree' (duration: 293.005666ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:18.664532Z","caller":"traceutil/trace.go:171","msg":"trace[1464828334] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"285.443002ms","start":"2026-04-17T18:47:18.379063Z","end":"2026-04-17T18:47:18.664506Z","steps":["trace[1464828334] 'read index received' (duration: 285.292858ms)","trace[1464828334] 'applied index is now lower than readState.Index' (duration: 149.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:18.664577Z","caller":"traceutil/trace.go:171","msg":"trace[1862954905] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"291.130365ms","start":"2026-04-17T18:47:18.37342Z","end":"2026-04-17T18:47:18.664551Z","steps":["trace[1862954905] 'process raft request' (duration: 290.92639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:18.664787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.700209ms","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-04-17T18:47:18.66484Z","caller":"traceutil/trace.go:171","msg":"trace[850744608] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2135; }","duration":"285.818573ms","start":"2026-04-17T18:47:18.379006Z","end":"2026-04-17T18:47:18.664825Z","steps":["trace[850744608] 'agreement among raft nodes before linearized reading' (duration: 285.643888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:18.688188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.500975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T18:47:18.688201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.320421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T18:47:18.6882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.895646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T18:47:18.688262Z","caller":"traceutil/trace.go:171","msg":"trace[857856310] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2136; }","duration":"271.436955ms","start":"2026-04-17T18:47:18.416808Z","end":"2026-04-17T18:47:18.688245Z","steps":["trace[857856310] 'agreement among raft nodes before linearized reading' (duration: 271.310781ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:18.68829Z","caller":"traceutil/trace.go:171","msg":"trace[96404285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2136; }","duration":"309.000278ms","start":"2026-04-17T18:47:18.37927Z","end":"2026-04-17T18:47:18.68827Z","steps":["trace[96404285] 'agreement among raft nodes before linearized reading' (duration: 308.752462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:18.68839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.448374ms","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-04-17T18:47:18.688435Z","caller":"traceutil/trace.go:171","msg":"trace[1505618662] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2136; }","duration":"291.549847ms","start":"2026-04-17T18:47:18.396872Z","end":"2026-04-17T18:47:18.688421Z","steps":["trace[1505618662] 'agreement among raft nodes before linearized reading' (duration: 291.416954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:18.688468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:18.379196Z","time spent":"309.173343ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T18:47:18.68824Z","caller":"traceutil/trace.go:171","msg":"trace[764548525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2136; }","duration":"119.613548ms","start":"2026-04-17T18:47:18.568613Z","end":"2026-04-17T18:47:18.688227Z","steps":["trace[764548525] 'agreement among raft nodes before linearized reading' (duration: 119.474644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:19.060591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.715442ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:19.060774Z","caller":"traceutil/trace.go:171","msg":"trace[1819875509] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2137; }","duration":"175.918357ms","start":"2026-04-17T18:47:18.884832Z","end":"2026-04-17T18:47:19.06075Z","steps":["trace[1819875509] 'range keys from in-memory index tree' (duration: 175.65616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:19.060766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.705299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:47:19.061041Z","caller":"traceutil/trace.go:171","msg":"trace[1431728755] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"367.972129ms","start":"2026-04-17T18:47:18.693052Z","end":"2026-04-17T18:47:19.061024Z","steps":["trace[1431728755] 'process raft request' (duration: 134.934761ms)","trace[1431728755] 'compare' (duration: 232.548005ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:47:19.061133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:18.693026Z","time spent":"368.081192ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:47:19.06124Z","caller":"traceutil/trace.go:171","msg":"trace[1789806951] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"367.752083ms","start":"2026-04-17T18:47:18.693451Z","end":"2026-04-17T18:47:19.061203Z","steps":["trace[1789806951] 'process raft request' (duration: 367.530947ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:19.061372Z","caller":"traceutil/trace.go:171","msg":"trace[981335288] linearizableReadLoop","detail":"{readStateIndex:2217; appliedIndex:2215; }","duration":"107.449469ms","start":"2026-04-17T18:47:18.953901Z","end":"2026-04-17T18:47:19.061351Z","steps":["trace[981335288] 'read index received' (duration: 55.891µs)","trace[981335288] 'applied index is now lower than readState.Index' (duration: 107.344117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:47:19.061432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:18.69342Z","time spent":"367.887877ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:47:19.061487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.582463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:47:19.061529Z","caller":"traceutil/trace.go:171","msg":"trace[1885418413] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2140; }","duration":"107.680815ms","start":"2026-04-17T18:47:18.953833Z","end":"2026-04-17T18:47:19.061514Z","steps":["trace[1885418413] 'agreement among raft nodes before linearized reading' (duration: 107.600563ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:48:36.774073Z","caller":"traceutil/trace.go:171","msg":"trace[289954632] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"145.839548ms","start":"2026-04-17T18:48:36.62821Z","end":"2026-04-17T18:48:36.774049Z","steps":["trace[289954632] 'process raft request' (duration: 145.685564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:48:36.999034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.9225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:48:36.999108Z","caller":"traceutil/trace.go:171","msg":"trace[2083076696] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2751; }","duration":"113.042383ms","start":"2026-04-17T18:48:36.886052Z","end":"2026-04-17T18:48:36.999094Z","steps":["trace[2083076696] 'count revisions from in-memory index tree' (duration: 112.821257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:48:36.999114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.136892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-17T18:48:36.999199Z","caller":"traceutil/trace.go:171","msg":"trace[864831771] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2751; }","duration":"121.259865ms","start":"2026-04-17T18:48:36.877918Z","end":"2026-04-17T18:48:36.999177Z","steps":["trace[864831771] 'range keys from in-memory index tree' (duration: 120.999668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:48:37.293628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.479834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:48:37.293709Z","caller":"traceutil/trace.go:171","msg":"trace[809110068] linearizableReadLoop","detail":"{readStateIndex:2856; appliedIndex:2855; }","duration":"197.877884ms","start":"2026-04-17T18:48:37.095818Z","end":"2026-04-17T18:48:37.293696Z","steps":["trace[809110068] 'read index received' (duration: 44.209084ms)","trace[809110068] 'applied index is now lower than readState.Index' (duration: 153.66769ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:48:37.293764Z","caller":"traceutil/trace.go:171","msg":"trace[1022603300] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"234.659897ms","start":"2026-04-17T18:48:37.059076Z","end":"2026-04-17T18:48:37.293736Z","steps":["trace[1022603300] 'process raft request' (duration: 81.005398ms)","trace[1022603300] 'compare' (duration: 153.235518ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:48:37.293817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.003047ms","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-04-17T18:48:37.293839Z","caller":"traceutil/trace.go:171","msg":"trace[1888237675] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2753; }","duration":"198.060879ms","start":"2026-04-17T18:48:37.09577Z","end":"2026-04-17T18:48:37.293831Z","steps":["trace[1888237675] 'agreement among raft nodes before linearized reading' (duration: 197.959926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:29.283066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.725357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:52:29.283162Z","caller":"traceutil/trace.go:171","msg":"trace[1975740889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4899; }","duration":"255.890651ms","start":"2026-04-17T18:52:29.027251Z","end":"2026-04-17T18:52:29.283141Z","steps":["trace[1975740889] 'range keys from in-memory index tree' (duration: 255.623283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:29.28373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.883454ms","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-04-17T18:52:29.283771Z","caller":"traceutil/trace.go:171","msg":"trace[1569708668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4899; }","duration":"156.940435ms","start":"2026-04-17T18:52:29.12682Z","end":"2026-04-17T18:52:29.28376Z","steps":["trace[1569708668] 'range keys from in-memory index tree' (duration: 156.678328ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:29.557204Z","caller":"traceutil/trace.go:171","msg":"trace[732224481] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"268.754004ms","start":"2026-04-17T18:52:29.288396Z","end":"2026-04-17T18:52:29.55715Z","steps":["trace[732224481] 'process raft request' (duration: 268.545789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:29.997347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.863462ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T18:52:29.9978Z","caller":"traceutil/trace.go:171","msg":"trace[1189361391] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"136.813627ms","start":"2026-04-17T18:52:29.860935Z","end":"2026-04-17T18:52:29.997748Z","steps":["trace[1189361391] 'process raft request' (duration: 136.523309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:31.981143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.541028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:52:31.981289Z","caller":"traceutil/trace.go:171","msg":"trace[2076812242] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"160.257484ms","start":"2026-04-17T18:52:31.821006Z","end":"2026-04-17T18:52:31.981264Z","steps":["trace[2076812242] 'process raft request' (duration: 50.49481ms)","trace[2076812242] 'compare' (duration: 109.260411ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:52:49.405741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.699059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe1d392d-019f-4c42-a8f7-7d6b213e7174\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-17T18:52:49.405853Z","caller":"traceutil/trace.go:171","msg":"trace[1322428634] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fe1d392d-019f-4c42-a8f7-7d6b213e7174; range_end:; response_count:1; response_revision:5306; }","duration":"107.874564ms","start":"2026-04-17T18:52:49.297952Z","end":"2026-04-17T18:52:49.405827Z","steps":["trace[1322428634] 'range keys from in-memory index tree' (duration: 107.449983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:49.931013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.091225ms","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-04-17T18:52:49.931093Z","caller":"traceutil/trace.go:171","msg":"trace[601664666] linearizableReadLoop","detail":"{readStateIndex:5500; appliedIndex:5499; }","duration":"102.675394ms","start":"2026-04-17T18:52:49.828392Z","end":"2026-04-17T18:52:49.931068Z","steps":["trace[601664666] 'read index received' (duration: 85.250908ms)","trace[601664666] 'applied index is now lower than readState.Index' (duration: 17.422936ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:52:49.931126Z","caller":"traceutil/trace.go:171","msg":"trace[1236968706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5307; }","duration":"204.222529ms","start":"2026-04-17T18:52:49.72688Z","end":"2026-04-17T18:52:49.931102Z","steps":["trace[1236968706] 'range keys from in-memory index tree' (duration: 203.799707ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:49.931206Z","caller":"traceutil/trace.go:171","msg":"trace[1610558320] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"153.682217ms","start":"2026-04-17T18:52:49.777479Z","end":"2026-04-17T18:52:49.931161Z","steps":["trace[1610558320] 'process raft request' (duration: 136.145569ms)","trace[1610558320] 'compare' (duration: 17.291252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:52:49.931285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.86784ms","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-04-17T18:52:49.931402Z","caller":"traceutil/trace.go:171","msg":"trace[1907660285] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5308; }","duration":"103.043184ms","start":"2026-04-17T18:52:49.82832Z","end":"2026-04-17T18:52:49.931363Z","steps":["trace[1907660285] 'agreement among raft nodes before linearized reading' (duration: 102.851649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:49.957644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.895747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:52:49.957733Z","caller":"traceutil/trace.go:171","msg":"trace[2035262505] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5309; }","duration":"101.02302ms","start":"2026-04-17T18:52:49.85668Z","end":"2026-04-17T18:52:49.957703Z","steps":["trace[2035262505] 'agreement among raft nodes before linearized reading' (duration: 100.887216ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:01.218207Z","caller":"traceutil/trace.go:171","msg":"trace[1987138537] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"111.171242ms","start":"2026-04-17T18:53:01.106996Z","end":"2026-04-17T18:53:01.218167Z","steps":["trace[1987138537] 'process raft request' (duration: 82.845985ms)","trace[1987138537] 'compare' (duration: 28.133132ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:01.22712Z","caller":"traceutil/trace.go:171","msg":"trace[1024068116] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"118.57833ms","start":"2026-04-17T18:53:01.108521Z","end":"2026-04-17T18:53:01.2271Z","steps":["trace[1024068116] 'process raft request' (duration: 118.530199ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:01.227225Z","caller":"traceutil/trace.go:171","msg":"trace[1100602160] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"119.987178ms","start":"2026-04-17T18:53:01.107194Z","end":"2026-04-17T18:53:01.227181Z","steps":["trace[1100602160] 'process raft request' (duration: 119.822834ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:01.227336Z","caller":"traceutil/trace.go:171","msg":"trace[512187638] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"120.309257ms","start":"2026-04-17T18:53:01.107011Z","end":"2026-04-17T18:53:01.22732Z","steps":["trace[512187638] 'process raft request' (duration: 119.890316ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:41.01748Z","caller":"traceutil/trace.go:171","msg":"trace[339554248] transaction","detail":"{read_only:false; response_revision:5810; number_of_response:1; }","duration":"118.377334ms","start":"2026-04-17T18:53:40.899079Z","end":"2026-04-17T18:53:41.017456Z","steps":["trace[339554248] 'process raft request' (duration: 118.21297ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:54:21.650857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-04-17T18:54:21.722926Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"70.233765ms","hash":2642414291,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":33906688,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T18:54:21.722991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2642414291,"revision":2998,"compact-revision":-1} {"level":"warn","ts":"2026-04-17T18:58:56.738612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.483092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gbv6m\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:58:56.738714Z","caller":"traceutil/trace.go:171","msg":"trace[1960766305] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gbv6m; range_end:; response_count:1; response_revision:8144; }","duration":"299.709727ms","start":"2026-04-17T18:58:56.438982Z","end":"2026-04-17T18:58:56.738692Z","steps":["trace[1960766305] 'range keys from in-memory index tree' (duration: 299.214035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:56.738859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.893404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:58:56.738957Z","caller":"traceutil/trace.go:171","msg":"trace[478182102] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8144; }","duration":"106.052568ms","start":"2026-04-17T18:58:56.632883Z","end":"2026-04-17T18:58:56.738936Z","steps":["trace[478182102] 'count revisions from in-memory index tree' (duration: 105.75403ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:56.974129Z","caller":"traceutil/trace.go:171","msg":"trace[169850994] transaction","detail":"{read_only:false; response_revision:8145; number_of_response:1; }","duration":"229.986059ms","start":"2026-04-17T18:58:56.74411Z","end":"2026-04-17T18:58:56.974096Z","steps":["trace[169850994] 'process raft request' (duration: 229.760203ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:56.996392Z","caller":"traceutil/trace.go:171","msg":"trace[423709029] linearizableReadLoop","detail":"{readStateIndex:8428; appliedIndex:8426; }","duration":"193.977987ms","start":"2026-04-17T18:58:56.802378Z","end":"2026-04-17T18:58:56.996356Z","steps":["trace[423709029] 'read index received' (duration: 171.488762ms)","trace[423709029] 'applied index is now lower than readState.Index' (duration: 22.488015ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:58:56.996453Z","caller":"traceutil/trace.go:171","msg":"trace[1787679945] transaction","detail":"{read_only:false; response_revision:8146; number_of_response:1; }","duration":"252.310029ms","start":"2026-04-17T18:58:56.744118Z","end":"2026-04-17T18:58:56.996428Z","steps":["trace[1787679945] 'process raft request' (duration: 252.078593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:56.99667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.193612ms","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-04-17T18:58:56.996717Z","caller":"traceutil/trace.go:171","msg":"trace[1059244210] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8146; }","duration":"142.297895ms","start":"2026-04-17T18:58:56.854399Z","end":"2026-04-17T18:58:56.996697Z","steps":["trace[1059244210] 'agreement among raft nodes before linearized reading' (duration: 142.12055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:56.996836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.41903ms","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-04-17T18:58:56.996931Z","caller":"traceutil/trace.go:171","msg":"trace[2039344809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8146; }","duration":"194.573664ms","start":"2026-04-17T18:58:56.802333Z","end":"2026-04-17T18:58:56.996907Z","steps":["trace[2039344809] 'agreement among raft nodes before linearized reading' (duration: 194.192984ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:09.624729Z","caller":"traceutil/trace.go:171","msg":"trace[319704283] transaction","detail":"{read_only:false; response_revision:8213; number_of_response:1; }","duration":"206.67183ms","start":"2026-04-17T18:59:09.418025Z","end":"2026-04-17T18:59:09.624697Z","steps":["trace[319704283] 'process raft request' (duration: 206.470535ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:09.635744Z","caller":"traceutil/trace.go:171","msg":"trace[1085016052] transaction","detail":"{read_only:false; response_revision:8214; number_of_response:1; }","duration":"104.29793ms","start":"2026-04-17T18:59:09.531428Z","end":"2026-04-17T18:59:09.635725Z","steps":["trace[1085016052] 'process raft request' (duration: 104.028803ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:21.6591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6113} {"level":"info","ts":"2026-04-17T18:59:21.767516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6113,"took":"106.245173ms","hash":4289604918,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":22884352,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-17T18:59:21.767576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4289604918,"revision":6113,"compact-revision":2998} {"level":"info","ts":"2026-04-17T18:59:41.555408Z","caller":"traceutil/trace.go:171","msg":"trace[273806999] linearizableReadLoop","detail":"{readStateIndex:8731; appliedIndex:8729; }","duration":"181.422429ms","start":"2026-04-17T18:59:41.373951Z","end":"2026-04-17T18:59:41.555374Z","steps":["trace[273806999] 'read index received' (duration: 6.056093ms)","trace[273806999] 'applied index is now lower than readState.Index' (duration: 175.364716ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:59:41.555479Z","caller":"traceutil/trace.go:171","msg":"trace[242858730] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"182.772755ms","start":"2026-04-17T18:59:41.372646Z","end":"2026-04-17T18:59:41.555419Z","steps":["trace[242858730] 'process raft request' (duration: 112.406779ms)","trace[242858730] 'compare' (duration: 70.042527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:41.555727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.764718ms","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-04-17T18:59:41.555815Z","caller":"traceutil/trace.go:171","msg":"trace[964192004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8436; }","duration":"181.884041ms","start":"2026-04-17T18:59:41.373912Z","end":"2026-04-17T18:59:41.555796Z","steps":["trace[964192004] 'agreement among raft nodes before linearized reading' (duration: 181.660115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:41.556056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.054421ms","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-04-17T18:59:41.556157Z","caller":"traceutil/trace.go:171","msg":"trace[1270083673] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8436; }","duration":"177.187124ms","start":"2026-04-17T18:59:41.378951Z","end":"2026-04-17T18:59:41.556138Z","steps":["trace[1270083673] 'agreement among raft nodes before linearized reading' (duration: 176.881236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:41.556306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.235966ms","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-04-17T18:59:41.556386Z","caller":"traceutil/trace.go:171","msg":"trace[193443538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8436; }","duration":"177.403641ms","start":"2026-04-17T18:59:41.378964Z","end":"2026-04-17T18:59:41.556368Z","steps":["trace[193443538] 'agreement among raft nodes before linearized reading' (duration: 176.980529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:51.434523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.012196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T18:59:51.434638Z","caller":"traceutil/trace.go:171","msg":"trace[1920570830] linearizableReadLoop","detail":"{readStateIndex:8829; appliedIndex:8828; }","duration":"162.461538ms","start":"2026-04-17T18:59:51.272159Z","end":"2026-04-17T18:59:51.43462Z","steps":["trace[1920570830] 'read index received' (duration: 46.069131ms)","trace[1920570830] 'applied index is now lower than readState.Index' (duration: 116.390977ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:59:51.434701Z","caller":"traceutil/trace.go:171","msg":"trace[681223379] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8530; }","duration":"163.25512ms","start":"2026-04-17T18:59:51.271407Z","end":"2026-04-17T18:59:51.434662Z","steps":["trace[681223379] 'process raft request' (duration: 46.954926ms)","trace[681223379] 'compare' (duration: 115.650027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:51.434975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.813738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bcm2d\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-17T18:59:51.435056Z","caller":"traceutil/trace.go:171","msg":"trace[1882781012] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bcm2d; range_end:; response_count:1; response_revision:8530; }","duration":"162.932572ms","start":"2026-04-17T18:59:51.272103Z","end":"2026-04-17T18:59:51.435036Z","steps":["trace[1882781012] 'agreement among raft nodes before linearized reading' (duration: 162.666634ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:51.908977Z","caller":"traceutil/trace.go:171","msg":"trace[495267134] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"116.610453ms","start":"2026-04-17T18:59:51.792331Z","end":"2026-04-17T18:59:51.908941Z","steps":["trace[495267134] 'process raft request' (duration: 116.319675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:52.184622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.145685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:59:52.185156Z","caller":"traceutil/trace.go:171","msg":"trace[1801610745] linearizableReadLoop","detail":"{readStateIndex:8837; appliedIndex:8835; }","duration":"302.77859ms","start":"2026-04-17T18:59:51.882358Z","end":"2026-04-17T18:59:52.185137Z","steps":["trace[1801610745] 'read index received' (duration: 26.35707ms)","trace[1801610745] 'applied index is now lower than readState.Index' (duration: 276.42005ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:59:52.185295Z","caller":"traceutil/trace.go:171","msg":"trace[40887166] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"382.669773ms","start":"2026-04-17T18:59:51.802585Z","end":"2026-04-17T18:59:52.185254Z","steps":["trace[40887166] 'process raft request' (duration: 247.712856ms)","trace[40887166] 'compare' (duration: 133.94312ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:59:52.185366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:51.802566Z","time spent":"382.762205ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:59:52.185553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.187441ms","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-04-17T18:59:52.185618Z","caller":"traceutil/trace.go:171","msg":"trace[2002530129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8538; }","duration":"303.294044ms","start":"2026-04-17T18:59:51.882307Z","end":"2026-04-17T18:59:52.185602Z","steps":["trace[2002530129] 'agreement among raft nodes before linearized reading' (duration: 303.114239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:52.185662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:51.882281Z","time spent":"303.372586ms","remote":"127.0.0.1:39008","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":"info","ts":"2026-04-17T18:59:52.185676Z","caller":"traceutil/trace.go:171","msg":"trace[571250541] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"130.062105ms","start":"2026-04-17T18:59:52.055601Z","end":"2026-04-17T18:59:52.185663Z","steps":["trace[571250541] 'process raft request' (duration: 129.606463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:52.185882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.304003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-17T18:59:52.185922Z","caller":"traceutil/trace.go:171","msg":"trace[344792661] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8539; }","duration":"100.385675ms","start":"2026-04-17T18:59:52.085526Z","end":"2026-04-17T18:59:52.185912Z","steps":["trace[344792661] 'agreement among raft nodes before linearized reading' (duration: 100.280612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:52.185949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.80109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:59:52.186955Z","caller":"traceutil/trace.go:171","msg":"trace[1594560047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8539; }","duration":"159.875029ms","start":"2026-04-17T18:59:52.027056Z","end":"2026-04-17T18:59:52.186931Z","steps":["trace[1594560047] 'agreement among raft nodes before linearized reading' (duration: 158.8087ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:52.350293Z","caller":"traceutil/trace.go:171","msg":"trace[392353206] linearizableReadLoop","detail":"{readStateIndex:8839; appliedIndex:8838; }","duration":"159.422416ms","start":"2026-04-17T18:59:52.190848Z","end":"2026-04-17T18:59:52.35027Z","steps":["trace[392353206] 'read index received' (duration: 150.263599ms)","trace[392353206] 'applied index is now lower than readState.Index' (duration: 9.157957ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:59:52.350427Z","caller":"traceutil/trace.go:171","msg":"trace[1745790280] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"160.541426ms","start":"2026-04-17T18:59:52.18984Z","end":"2026-04-17T18:59:52.350382Z","steps":["trace[1745790280] 'process raft request' (duration: 151.256046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:52.350481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.623021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19384"} {"level":"info","ts":"2026-04-17T18:59:52.350739Z","caller":"traceutil/trace.go:171","msg":"trace[1712134752] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8540; }","duration":"159.878009ms","start":"2026-04-17T18:59:52.190821Z","end":"2026-04-17T18:59:52.350699Z","steps":["trace[1712134752] 'agreement among raft nodes before linearized reading' (duration: 159.54906ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:52.4227Z","caller":"traceutil/trace.go:171","msg":"trace[1305457155] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"181.000527ms","start":"2026-04-17T18:59:52.241662Z","end":"2026-04-17T18:59:52.422663Z","steps":["trace[1305457155] 'process raft request' (duration: 180.75657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:52.422752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.060635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:59:52.42285Z","caller":"traceutil/trace.go:171","msg":"trace[2077321046] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8541; }","duration":"153.169458ms","start":"2026-04-17T18:59:52.269648Z","end":"2026-04-17T18:59:52.422818Z","steps":["trace[2077321046] 'agreement among raft nodes before linearized reading' (duration: 152.998303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:59:52.422943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.673623ms","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-04-17T18:59:52.423961Z","caller":"traceutil/trace.go:171","msg":"trace[1007040787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8541; }","duration":"183.71387ms","start":"2026-04-17T18:59:52.240219Z","end":"2026-04-17T18:59:52.423933Z","steps":["trace[1007040787] 'agreement among raft nodes before linearized reading' (duration: 182.553439ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:00:41.995477Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002440.wal"} {"level":"info","ts":"2026-04-17T19:02:00.931165Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca739d563b259b56","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T19:02:00.93634Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-17T19:02:00.936461Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-17T19:02:59.001468Z","caller":"traceutil/trace.go:171","msg":"trace[96913600] linearizableReadLoop","detail":"{readStateIndex:10429; appliedIndex:10427; }","duration":"112.922305ms","start":"2026-04-17T19:02:58.888473Z","end":"2026-04-17T19:02:59.001395Z","steps":["trace[96913600] 'read index received' (duration: 43.43965ms)","trace[96913600] 'applied index is now lower than readState.Index' (duration: 69.480835ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:02:59.001571Z","caller":"traceutil/trace.go:171","msg":"trace[202369228] transaction","detail":"{read_only:false; response_revision:10084; number_of_response:1; }","duration":"115.048851ms","start":"2026-04-17T19:02:58.886472Z","end":"2026-04-17T19:02:59.001521Z","steps":["trace[202369228] 'process raft request' (duration: 54.871595ms)","trace[202369228] 'compare' (duration: 59.714814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:02:59.001707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.204402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T19:02:59.001753Z","caller":"traceutil/trace.go:171","msg":"trace[1054195014] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:10084; }","duration":"113.322765ms","start":"2026-04-17T19:02:58.888415Z","end":"2026-04-17T19:02:59.001737Z","steps":["trace[1054195014] 'agreement among raft nodes before linearized reading' (duration: 113.192661ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:04:21.665937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8282} {"level":"info","ts":"2026-04-17T19:04:21.746586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8282,"took":"78.350889ms","hash":201135479,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":24748032,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-17T19:04:21.74666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":201135479,"revision":8282,"compact-revision":6113} {"level":"info","ts":"2026-04-17T19:07:28.378761Z","caller":"traceutil/trace.go:171","msg":"trace[1845426898] transaction","detail":"{read_only:false; response_revision:11644; number_of_response:1; }","duration":"112.216692ms","start":"2026-04-17T19:07:28.266508Z","end":"2026-04-17T19:07:28.378725Z","steps":["trace[1845426898] 'process raft request' (duration: 112.011617ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:08:43.117017Z","caller":"traceutil/trace.go:171","msg":"trace[1980299508] transaction","detail":"{read_only:false; response_revision:12023; number_of_response:1; }","duration":"162.824201ms","start":"2026-04-17T19:08:42.954151Z","end":"2026-04-17T19:08:43.116975Z","steps":["trace[1980299508] 'process raft request' (duration: 162.625425ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:08:43.117907Z","caller":"traceutil/trace.go:171","msg":"trace[1210914963] transaction","detail":"{read_only:false; response_revision:12024; number_of_response:1; }","duration":"163.129699ms","start":"2026-04-17T19:08:42.954759Z","end":"2026-04-17T19:08:43.117889Z","steps":["trace[1210914963] 'process raft request' (duration: 163.044497ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:08:43.573579Z","caller":"traceutil/trace.go:171","msg":"trace[1157573505] transaction","detail":"{read_only:false; response_revision:12026; number_of_response:1; }","duration":"293.588901ms","start":"2026-04-17T19:08:43.279965Z","end":"2026-04-17T19:08:43.573554Z","steps":["trace[1157573505] 'process raft request' (duration: 292.898923ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:08:43.573734Z","caller":"traceutil/trace.go:171","msg":"trace[231869002] linearizableReadLoop","detail":"{readStateIndex:12448; appliedIndex:12447; }","duration":"205.979435ms","start":"2026-04-17T19:08:43.367745Z","end":"2026-04-17T19:08:43.573724Z","steps":["trace[231869002] 'read index received' (duration: 200.855898ms)","trace[231869002] 'applied index is now lower than readState.Index' (duration: 5.122587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:08:43.574233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.468118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-17T19:08:43.574273Z","caller":"traceutil/trace.go:171","msg":"trace[2080644611] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:12027; }","duration":"206.56292ms","start":"2026-04-17T19:08:43.367698Z","end":"2026-04-17T19:08:43.574261Z","steps":["trace[2080644611] 'agreement among raft nodes before linearized reading' (duration: 206.408306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:08:43.574645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.208478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-17T19:08:43.574743Z","caller":"traceutil/trace.go:171","msg":"trace[895733876] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12027; }","duration":"204.333112ms","start":"2026-04-17T19:08:43.370391Z","end":"2026-04-17T19:08:43.574724Z","steps":["trace[895733876] 'agreement among raft nodes before linearized reading' (duration: 204.032074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:08:45.883649Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.837660272s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T19:08:45.885119Z","caller":"traceutil/trace.go:171","msg":"trace[1256675390] transaction","detail":"{read_only:false; response_revision:12028; number_of_response:1; }","duration":"1.839244845s","start":"2026-04-17T19:08:44.04585Z","end":"2026-04-17T19:08:45.885095Z","steps":["trace[1256675390] 'process raft request' (duration: 1.838718811s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:08:45.885213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:08:44.045826Z","time spent":"1.839317837s","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T19:08:53.182997Z","caller":"traceutil/trace.go:171","msg":"trace[1205106129] transaction","detail":"{read_only:false; response_revision:12070; number_of_response:1; }","duration":"598.239626ms","start":"2026-04-17T19:08:52.584727Z","end":"2026-04-17T19:08:53.182966Z","steps":["trace[1205106129] 'process raft request' (duration: 598.043621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:08:53.183153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T19:08:52.584696Z","time spent":"598.37523ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T19:08:53.691347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.322693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T19:08:53.691437Z","caller":"traceutil/trace.go:171","msg":"trace[457660019] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12070; }","duration":"104.469577ms","start":"2026-04-17T19:08:53.586951Z","end":"2026-04-17T19:08:53.691421Z","steps":["trace[457660019] 'count revisions from in-memory index tree' (duration: 104.231231ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:09:21.671155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10676} {"level":"info","ts":"2026-04-17T19:09:21.752932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10676,"took":"79.713095ms","hash":560828136,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":20332544,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-17T19:09:21.752995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":560828136,"revision":10676,"compact-revision":8282}