{"level":"warn","ts":"2026-04-09T22:29:41.613721Z","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-09T22:29:41.613801Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-09T22:29:41.613989Z","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-09T22:29:41.614021Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-09T22:29:41.614062Z","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-09T22:29:41.615428Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-09T22:29:41.615592Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-09T22:29:41.619594Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.578529ms"} {"level":"info","ts":"2026-04-09T22:29:41.635511Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-09T22:29:41.635653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T22:29:41.635683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-09T22:29:41.635716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T22:29:41.635729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-09T22:29:41.635791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-09T22:29:41.6455Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T22:29:41.647619Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T22:29:41.649381Z","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-09T22:29:41.651761Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T22:29:41.651947Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T22:29:41.652057Z","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-09T22:29:41.652202Z","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-09T22:29:41.652231Z","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-09T22:29:41.653546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-09T22:29:41.653685Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-09T22:29:41.657221Z","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-09T22:29:41.657519Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T22:29:41.657536Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-09T22:29:41.65757Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T22:29:41.657604Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-09T22:29:42.437369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T22:29:42.437445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T22:29:42.437484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-09T22:29:42.437502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-09T22:29:42.43752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-09T22:29:42.437537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-09T22:29:42.43755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-09T22:29:42.438478Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T22:29:42.438575Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:29:42.438659Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:42.438786Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:29:42.438859Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T22:29:42.438955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T22:29:42.441166Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:42.441396Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:42.441454Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:42.443324Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T22:29:42.443326Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"warn","ts":"2026-04-09T22:30:01.041494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.931724ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-09T22:30:01.041637Z","caller":"traceutil/trace.go:171","msg":"trace[1496987771] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"210.633795ms","start":"2026-04-09T22:30:00.830987Z","end":"2026-04-09T22:30:01.041621Z","steps":["trace[1496987771] 'read index received' (duration: 3.411393ms)","trace[1496987771] 'applied index is now lower than readState.Index' (duration: 207.220812ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:01.041733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.735979ms","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-09T22:30:01.041766Z","caller":"traceutil/trace.go:171","msg":"trace[402640590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"210.81076ms","start":"2026-04-09T22:30:00.830947Z","end":"2026-04-09T22:30:01.041758Z","steps":["trace[402640590] 'agreement among raft nodes before linearized reading' (duration: 210.730718ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:18.777313Z","caller":"traceutil/trace.go:171","msg":"trace[1860134687] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"332.981527ms","start":"2026-04-09T22:30:18.444314Z","end":"2026-04-09T22:30:18.777296Z","steps":["trace[1860134687] 'process raft request' (duration: 332.844203ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:18.777485Z","caller":"traceutil/trace.go:171","msg":"trace[244321398] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:632; }","duration":"324.575186ms","start":"2026-04-09T22:30:18.452836Z","end":"2026-04-09T22:30:18.777411Z","steps":["trace[244321398] 'read index received' (duration: 324.548725ms)","trace[244321398] 'applied index is now lower than readState.Index' (duration: 22.851µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:18.777685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.850263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-09T22:30:18.777736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:18.444279Z","time spent":"333.09061ms","remote":"127.0.0.1:55092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:18.777767Z","caller":"traceutil/trace.go:171","msg":"trace[1168187789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"324.966806ms","start":"2026-04-09T22:30:18.452781Z","end":"2026-04-09T22:30:18.777747Z","steps":["trace[1168187789] 'agreement among raft nodes before linearized reading' (duration: 324.784961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:18.777864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:18.452758Z","time spent":"325.09606ms","remote":"127.0.0.1:55176","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-04-09T22:30:18.840912Z","caller":"traceutil/trace.go:171","msg":"trace[2013200668] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"373.967842ms","start":"2026-04-09T22:30:18.466894Z","end":"2026-04-09T22:30:18.840862Z","steps":["trace[2013200668] 'process raft request' (duration: 373.217372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:18.841175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:18.466852Z","time spent":"374.159568ms","remote":"127.0.0.1:55012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:19.100067Z","caller":"traceutil/trace.go:171","msg":"trace[2125825126] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"197.595718ms","start":"2026-04-09T22:30:18.902446Z","end":"2026-04-09T22:30:19.100042Z","steps":["trace[2125825126] 'process raft request' (duration: 197.545356ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:19.100087Z","caller":"traceutil/trace.go:171","msg":"trace[2119960162] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"318.228691ms","start":"2026-04-09T22:30:18.781824Z","end":"2026-04-09T22:30:19.100053Z","steps":["trace[2119960162] 'process raft request' (duration: 305.937493ms)","trace[2119960162] 'compare' (duration: 12.099333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:19.100527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:18.781808Z","time spent":"318.55456ms","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:19.301449Z","caller":"traceutil/trace.go:171","msg":"trace[489464098] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"105.503758ms","start":"2026-04-09T22:30:19.195909Z","end":"2026-04-09T22:30:19.301413Z","steps":["trace[489464098] 'process raft request' (duration: 105.336433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:35.059407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.108915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:35.059512Z","caller":"traceutil/trace.go:171","msg":"trace[134606542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"102.26407ms","start":"2026-04-09T22:30:34.957227Z","end":"2026-04-09T22:30:35.059491Z","steps":["trace[134606542] 'range keys from in-memory index tree' (duration: 102.032643ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:47.601367Z","caller":"traceutil/trace.go:171","msg":"trace[1415606611] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"148.266402ms","start":"2026-04-09T22:31:47.453062Z","end":"2026-04-09T22:31:47.601328Z","steps":["trace[1415606611] 'process raft request' (duration: 78.997094ms)","trace[1415606611] 'compare' (duration: 69.019211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:31:48.010985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.764465ms","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-04-09T22:31:48.010985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.738723ms","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-09T22:31:48.011046Z","caller":"traceutil/trace.go:171","msg":"trace[1633346049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1834; }","duration":"279.867198ms","start":"2026-04-09T22:31:47.731164Z","end":"2026-04-09T22:31:48.011031Z","steps":["trace[1633346049] 'range keys from in-memory index tree' (duration: 279.608281ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:48.011066Z","caller":"traceutil/trace.go:171","msg":"trace[1674301953] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1834; }","duration":"222.868256ms","start":"2026-04-09T22:31:47.788172Z","end":"2026-04-09T22:31:48.011041Z","steps":["trace[1674301953] 'range keys from in-memory index tree' (duration: 222.545557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:48.011938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.676099ms","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-09T22:31:48.012022Z","caller":"traceutil/trace.go:171","msg":"trace[288598934] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1834; }","duration":"273.791361ms","start":"2026-04-09T22:31:47.738212Z","end":"2026-04-09T22:31:48.012003Z","steps":["trace[288598934] 'range keys from in-memory index tree' (duration: 272.519566ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:48.187592Z","caller":"traceutil/trace.go:171","msg":"trace[1535088001] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"117.568231ms","start":"2026-04-09T22:31:48.069989Z","end":"2026-04-09T22:31:48.187557Z","steps":["trace[1535088001] 'process raft request' (duration: 117.485949ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:48.187811Z","caller":"traceutil/trace.go:171","msg":"trace[637516982] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"168.177007ms","start":"2026-04-09T22:31:48.019537Z","end":"2026-04-09T22:31:48.187714Z","steps":["trace[637516982] 'process raft request' (duration: 167.880679ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:48.188208Z","caller":"traceutil/trace.go:171","msg":"trace[170114138] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"172.105825ms","start":"2026-04-09T22:31:48.016034Z","end":"2026-04-09T22:31:48.18814Z","steps":["trace[170114138] 'process raft request' (duration: 171.19483ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:59.96669Z","caller":"traceutil/trace.go:171","msg":"trace[2054865187] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"317.751656ms","start":"2026-04-09T22:31:59.648918Z","end":"2026-04-09T22:31:59.96667Z","steps":["trace[2054865187] 'process raft request' (duration: 317.18083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:59.966841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:59.648896Z","time spent":"317.863209ms","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:31:59.966878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.620234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T22:31:59.966933Z","caller":"traceutil/trace.go:171","msg":"trace[1770989912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1935; }","duration":"251.707096ms","start":"2026-04-09T22:31:59.71521Z","end":"2026-04-09T22:31:59.966917Z","steps":["trace[1770989912] 'agreement among raft nodes before linearized reading' (duration: 251.520401ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:59.966675Z","caller":"traceutil/trace.go:171","msg":"trace[518612733] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"251.406468ms","start":"2026-04-09T22:31:59.715244Z","end":"2026-04-09T22:31:59.96665Z","steps":["trace[518612733] 'read index received' (duration: 250.74812ms)","trace[518612733] 'applied index is now lower than readState.Index' (duration: 657.148µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:01.089108Z","caller":"traceutil/trace.go:171","msg":"trace[699855586] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"100.827862ms","start":"2026-04-09T22:32:00.988256Z","end":"2026-04-09T22:32:01.089084Z","steps":["trace[699855586] 'process raft request' (duration: 100.795182ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:01.089157Z","caller":"traceutil/trace.go:171","msg":"trace[1135707648] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"156.58339ms","start":"2026-04-09T22:32:00.932539Z","end":"2026-04-09T22:32:01.089122Z","steps":["trace[1135707648] 'process raft request' (duration: 75.236192ms)","trace[1135707648] 'compare' (duration: 80.953728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:01.089282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.240226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:32:01.089157Z","caller":"traceutil/trace.go:171","msg":"trace[1929635294] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2015; }","duration":"131.096841ms","start":"2026-04-09T22:32:00.958025Z","end":"2026-04-09T22:32:01.089122Z","steps":["trace[1929635294] 'read index received' (duration: 49.779523ms)","trace[1929635294] 'applied index is now lower than readState.Index' (duration: 81.313728ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:01.089364Z","caller":"traceutil/trace.go:171","msg":"trace[1749875855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1945; }","duration":"131.362659ms","start":"2026-04-09T22:32:00.957979Z","end":"2026-04-09T22:32:01.089341Z","steps":["trace[1749875855] 'agreement among raft nodes before linearized reading' (duration: 131.229486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:05.000318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.374256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:32:05.000385Z","caller":"traceutil/trace.go:171","msg":"trace[1623623757] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1971; }","duration":"217.489089ms","start":"2026-04-09T22:32:04.782879Z","end":"2026-04-09T22:32:05.000368Z","steps":["trace[1623623757] 'count revisions from in-memory index tree' (duration: 217.287183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:05.000326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.6732ms","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-04-09T22:32:05.000467Z","caller":"traceutil/trace.go:171","msg":"trace[1854936940] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1971; }","duration":"253.828434ms","start":"2026-04-09T22:32:04.746598Z","end":"2026-04-09T22:32:05.000426Z","steps":["trace[1854936940] 'count revisions from in-memory index tree' (duration: 253.604419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:37.53672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.984579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:32:37.536987Z","caller":"traceutil/trace.go:171","msg":"trace[766503258] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"176.035593ms","start":"2026-04-09T22:32:37.360923Z","end":"2026-04-09T22:32:37.536958Z","steps":["trace[766503258] 'process raft request' (duration: 71.656443ms)","trace[766503258] 'compare' (duration: 103.829775ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:38.120161Z","caller":"traceutil/trace.go:171","msg":"trace[1437480322] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"104.616827ms","start":"2026-04-09T22:32:38.015517Z","end":"2026-04-09T22:32:38.120134Z","steps":["trace[1437480322] 'process raft request' (duration: 104.389471ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:43.684169Z","caller":"traceutil/trace.go:171","msg":"trace[1240392082] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2263; }","duration":"101.838401ms","start":"2026-04-09T22:32:43.5823Z","end":"2026-04-09T22:32:43.684138Z","steps":["trace[1240392082] 'read index received' (duration: 101.647995ms)","trace[1240392082] 'applied index is now lower than readState.Index' (duration: 188.976µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:43.684274Z","caller":"traceutil/trace.go:171","msg":"trace[1095519235] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"115.145675ms","start":"2026-04-09T22:32:43.56909Z","end":"2026-04-09T22:32:43.684236Z","steps":["trace[1095519235] 'process raft request' (duration: 114.868288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:43.684399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.075587ms","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-09T22:32:43.684445Z","caller":"traceutil/trace.go:171","msg":"trace[1994308494] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2184; }","duration":"102.16866ms","start":"2026-04-09T22:32:43.58226Z","end":"2026-04-09T22:32:43.684429Z","steps":["trace[1994308494] 'agreement among raft nodes before linearized reading' (duration: 101.993815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:43.995133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.145173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-09T22:32:43.99524Z","caller":"traceutil/trace.go:171","msg":"trace[455770891] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2184; }","duration":"208.296867ms","start":"2026-04-09T22:32:43.786919Z","end":"2026-04-09T22:32:43.995216Z","steps":["trace[455770891] 'range keys from in-memory index tree' (duration: 207.983609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:43.995796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.846063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:86044"} {"level":"info","ts":"2026-04-09T22:32:43.995869Z","caller":"traceutil/trace.go:171","msg":"trace[1204024893] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2184; }","duration":"204.992867ms","start":"2026-04-09T22:32:43.790862Z","end":"2026-04-09T22:32:43.995855Z","steps":["trace[1204024893] 'range keys from in-memory index tree' (duration: 204.088742ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:44.35698Z","caller":"traceutil/trace.go:171","msg":"trace[450194845] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"168.397594ms","start":"2026-04-09T22:32:44.188537Z","end":"2026-04-09T22:32:44.356935Z","steps":["trace[450194845] 'process raft request' (duration: 163.030948ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:00.581642Z","caller":"traceutil/trace.go:171","msg":"trace[210698600] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"105.302211ms","start":"2026-04-09T22:34:00.476312Z","end":"2026-04-09T22:34:00.581614Z","steps":["trace[210698600] 'process raft request' (duration: 105.129885ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:36:36.181229Z","caller":"traceutil/trace.go:171","msg":"trace[203312017] transaction","detail":"{read_only:false; response_revision:3814; number_of_response:1; }","duration":"206.516369ms","start":"2026-04-09T22:36:35.974678Z","end":"2026-04-09T22:36:36.181195Z","steps":["trace[203312017] 'process raft request' (duration: 206.276663ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:37:56.083622Z","caller":"traceutil/trace.go:171","msg":"trace[1283165758] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"102.160125ms","start":"2026-04-09T22:37:55.981425Z","end":"2026-04-09T22:37:56.083585Z","steps":["trace[1283165758] 'process raft request' (duration: 101.907768ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:00.689531Z","caller":"traceutil/trace.go:171","msg":"trace[20017576] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5181; }","duration":"219.861917ms","start":"2026-04-09T22:38:00.469642Z","end":"2026-04-09T22:38:00.689504Z","steps":["trace[20017576] 'read index received' (duration: 219.686012ms)","trace[20017576] 'applied index is now lower than readState.Index' (duration: 175.045µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:38:00.689639Z","caller":"traceutil/trace.go:171","msg":"trace[420381146] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"317.61983ms","start":"2026-04-09T22:38:00.371976Z","end":"2026-04-09T22:38:00.689596Z","steps":["trace[420381146] 'process raft request' (duration: 317.378083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:00.689794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:38:00.371949Z","time spent":"317.777725ms","remote":"127.0.0.1:43914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1813,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:38:00.68981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.153074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1914c7c8-a268-411d-a14d-4b3c4d0a696a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-09T22:38:00.689917Z","caller":"traceutil/trace.go:171","msg":"trace[948242262] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1914c7c8-a268-411d-a14d-4b3c4d0a696a; range_end:; response_count:1; response_revision:4995; }","duration":"220.322058ms","start":"2026-04-09T22:38:00.46958Z","end":"2026-04-09T22:38:00.689902Z","steps":["trace[948242262] 'agreement among raft nodes before linearized reading' (duration: 220.056441ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:00.758291Z","caller":"traceutil/trace.go:171","msg":"trace[840435584] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"214.682444ms","start":"2026-04-09T22:38:00.543587Z","end":"2026-04-09T22:38:00.758269Z","steps":["trace[840435584] 'process raft request' (duration: 214.54771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:00.758571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.82926ms","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-04-09T22:38:00.758752Z","caller":"traceutil/trace.go:171","msg":"trace[2122937749] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4996; }","duration":"222.131888ms","start":"2026-04-09T22:38:00.536597Z","end":"2026-04-09T22:38:00.758729Z","steps":["trace[2122937749] 'agreement among raft nodes before linearized reading' (duration: 221.697466ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:00.804706Z","caller":"traceutil/trace.go:171","msg":"trace[365430980] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"101.297551ms","start":"2026-04-09T22:38:00.703381Z","end":"2026-04-09T22:38:00.804679Z","steps":["trace[365430980] 'process raft request' (duration: 99.259485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:00.810462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.091563ms","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-09T22:38:00.810557Z","caller":"traceutil/trace.go:171","msg":"trace[1306648360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4997; }","duration":"114.243147ms","start":"2026-04-09T22:38:00.696291Z","end":"2026-04-09T22:38:00.810534Z","steps":["trace[1306648360] 'agreement among raft nodes before linearized reading' (duration: 113.980489ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:19.160268Z","caller":"traceutil/trace.go:171","msg":"trace[181160010] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"120.739495ms","start":"2026-04-09T22:38:19.03949Z","end":"2026-04-09T22:38:19.160229Z","steps":["trace[181160010] 'process raft request' (duration: 120.477408ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:19.4185Z","caller":"traceutil/trace.go:171","msg":"trace[2088868384] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"202.992963ms","start":"2026-04-09T22:38:19.215464Z","end":"2026-04-09T22:38:19.418457Z","steps":["trace[2088868384] 'process raft request' (duration: 104.964482ms)","trace[2088868384] 'compare' (duration: 97.644901ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:38:19.695545Z","caller":"traceutil/trace.go:171","msg":"trace[454422064] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"270.509166ms","start":"2026-04-09T22:38:19.425002Z","end":"2026-04-09T22:38:19.695512Z","steps":["trace[454422064] 'process raft request' (duration: 252.07696ms)","trace[454422064] 'compare' (duration: 18.085027ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:38:19.695885Z","caller":"traceutil/trace.go:171","msg":"trace[1913396519] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"268.165572ms","start":"2026-04-09T22:38:19.427682Z","end":"2026-04-09T22:38:19.695847Z","steps":["trace[1913396519] 'process raft request' (duration: 267.7472ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:19.700174Z","caller":"traceutil/trace.go:171","msg":"trace[634855268] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"175.091267ms","start":"2026-04-09T22:38:19.525052Z","end":"2026-04-09T22:38:19.700143Z","steps":["trace[634855268] 'process raft request' (duration: 174.8669ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:39:42.465637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3024} {"level":"info","ts":"2026-04-09T22:39:42.552955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3024,"took":"85.664372ms","hash":2495939581,"current-db-size-bytes":58077184,"current-db-size":"58 MB","current-db-size-in-use-bytes":31670272,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-09T22:39:42.553034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2495939581,"revision":3024,"compact-revision":-1} {"level":"info","ts":"2026-04-09T22:39:48.237911Z","caller":"traceutil/trace.go:171","msg":"trace[1097167994] transaction","detail":"{read_only:false; response_revision:6162; number_of_response:1; }","duration":"194.737867ms","start":"2026-04-09T22:39:48.043142Z","end":"2026-04-09T22:39:48.23788Z","steps":["trace[1097167994] 'process raft request' (duration: 194.508691ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:39:48.239178Z","caller":"traceutil/trace.go:171","msg":"trace[1538855122] transaction","detail":"{read_only:false; response_revision:6163; number_of_response:1; }","duration":"194.49595ms","start":"2026-04-09T22:39:48.044638Z","end":"2026-04-09T22:39:48.239134Z","steps":["trace[1538855122] 'process raft request' (duration: 194.260564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.133933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095096595302367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T22:42:31.167128Z","caller":"traceutil/trace.go:171","msg":"trace[843332782] transaction","detail":"{read_only:false; response_revision:7293; number_of_response:1; }","duration":"265.308548ms","start":"2026-04-09T22:42:30.901778Z","end":"2026-04-09T22:42:31.167087Z","steps":["trace[843332782] 'process raft request' (duration: 264.979909ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:31.167272Z","caller":"traceutil/trace.go:171","msg":"trace[1273742998] linearizableReadLoop","detail":"{readStateIndex:7555; appliedIndex:7552; }","duration":"534.332378ms","start":"2026-04-09T22:42:30.632907Z","end":"2026-04-09T22:42:31.16724Z","steps":["trace[1273742998] 'read index received' (duration: 521.445903ms)","trace[1273742998] 'applied index is now lower than readState.Index' (duration: 12.884685ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:42:31.167381Z","caller":"traceutil/trace.go:171","msg":"trace[211560675] transaction","detail":"{read_only:false; response_revision:7294; number_of_response:1; }","duration":"265.420981ms","start":"2026-04-09T22:42:30.901915Z","end":"2026-04-09T22:42:31.167336Z","steps":["trace[211560675] 'process raft request' (duration: 265.084001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.167661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.646437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:42:31.167626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.661037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:42:31.167713Z","caller":"traceutil/trace.go:171","msg":"trace[351605933] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7294; }","duration":"534.719529ms","start":"2026-04-09T22:42:30.632979Z","end":"2026-04-09T22:42:31.167699Z","steps":["trace[351605933] 'agreement among raft nodes before linearized reading' (duration: 534.636506ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:31.167737Z","caller":"traceutil/trace.go:171","msg":"trace[1690384453] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:0; response_revision:7294; }","duration":"534.836852ms","start":"2026-04-09T22:42:30.632878Z","end":"2026-04-09T22:42:31.167715Z","steps":["trace[1690384453] 'agreement among raft nodes before linearized reading' (duration: 534.599616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.16775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:30.632965Z","time spent":"534.775821ms","remote":"127.0.0.1:55144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"warn","ts":"2026-04-09T22:42:31.167736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.701011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:42:31.167788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:30.632857Z","time spent":"534.922425ms","remote":"127.0.0.1:55144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" "} {"level":"warn","ts":"2026-04-09T22:42:31.167787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.003266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1984"} {"level":"info","ts":"2026-04-09T22:42:31.167814Z","caller":"traceutil/trace.go:171","msg":"trace[1257144975] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:7294; }","duration":"533.823514ms","start":"2026-04-09T22:42:30.633973Z","end":"2026-04-09T22:42:31.167796Z","steps":["trace[1257144975] 'agreement among raft nodes before linearized reading' (duration: 533.507326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.167801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.75432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:42:31.167836Z","caller":"traceutil/trace.go:171","msg":"trace[1358812608] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7294; }","duration":"266.10415ms","start":"2026-04-09T22:42:30.901716Z","end":"2026-04-09T22:42:31.16782Z","steps":["trace[1358812608] 'agreement among raft nodes before linearized reading' (duration: 265.984837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.167859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:30.633961Z","time spent":"533.886706ms","remote":"127.0.0.1:49224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:42:31.16763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.616376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:42:31.167889Z","caller":"traceutil/trace.go:171","msg":"trace[1842343802] range","detail":"{range_begin:/registry/jobs/openstack/barbican-rabbit-init; range_end:; response_count:0; response_revision:7294; }","duration":"534.945185ms","start":"2026-04-09T22:42:30.632914Z","end":"2026-04-09T22:42:31.16786Z","steps":["trace[1842343802] 'agreement among raft nodes before linearized reading' (duration: 534.75737ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:31.167912Z","caller":"traceutil/trace.go:171","msg":"trace[978788567] range","detail":"{range_begin:/registry/jobs/openstack/barbican-db-sync; range_end:; response_count:0; response_revision:7294; }","duration":"534.926534ms","start":"2026-04-09T22:42:30.632973Z","end":"2026-04-09T22:42:31.1679Z","steps":["trace[978788567] 'agreement among raft nodes before linearized reading' (duration: 534.510773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.167945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:30.632895Z","time spent":"535.041868ms","remote":"127.0.0.1:55144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/barbican-db-sync\" "} {"level":"warn","ts":"2026-04-09T22:42:31.167949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:30.632889Z","time spent":"535.043758ms","remote":"127.0.0.1:55144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/barbican-rabbit-init\" "} {"level":"warn","ts":"2026-04-09T22:42:31.168122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.402568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-09T22:42:31.167432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.497612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:42:31.168244Z","caller":"traceutil/trace.go:171","msg":"trace[913300451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7294; }","duration":"510.577224ms","start":"2026-04-09T22:42:30.657632Z","end":"2026-04-09T22:42:31.168209Z","steps":["trace[913300451] 'agreement among raft nodes before linearized reading' (duration: 510.204313ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:31.168253Z","caller":"traceutil/trace.go:171","msg":"trace[260833290] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:7294; }","duration":"535.363936ms","start":"2026-04-09T22:42:30.632874Z","end":"2026-04-09T22:42:31.168238Z","steps":["trace[260833290] 'agreement among raft nodes before linearized reading' (duration: 534.485742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.168299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:30.632855Z","time spent":"535.431959ms","remote":"127.0.0.1:37728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:42:31.168307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:30.657621Z","time spent":"510.669336ms","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T22:42:33.936816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.813878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:42:33.936991Z","caller":"traceutil/trace.go:171","msg":"trace[325262968] transaction","detail":"{read_only:false; response_revision:7313; number_of_response:1; }","duration":"142.464584ms","start":"2026-04-09T22:42:33.794508Z","end":"2026-04-09T22:42:33.936973Z","steps":["trace[325262968] 'process raft request' (duration: 142.406002ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:33.936984Z","caller":"traceutil/trace.go:171","msg":"trace[460845816] transaction","detail":"{read_only:false; response_revision:7312; number_of_response:1; }","duration":"700.468303ms","start":"2026-04-09T22:42:33.236466Z","end":"2026-04-09T22:42:33.936934Z","steps":["trace[460845816] 'process raft request' (duration: 393.458089ms)","trace[460845816] 'compare' (duration: 306.472569ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:42:33.937116Z","caller":"traceutil/trace.go:171","msg":"trace[1902069004] linearizableReadLoop","detail":"{readStateIndex:7573; appliedIndex:7572; }","duration":"535.19541ms","start":"2026-04-09T22:42:33.401911Z","end":"2026-04-09T22:42:33.937106Z","steps":["trace[1902069004] 'read index received' (duration: 227.882629ms)","trace[1902069004] 'applied index is now lower than readState.Index' (duration: 307.311661ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:42:33.9372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.280984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:42:33.937238Z","caller":"traceutil/trace.go:171","msg":"trace[159874353] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:7313; }","duration":"535.338645ms","start":"2026-04-09T22:42:33.401888Z","end":"2026-04-09T22:42:33.937227Z","steps":["trace[159874353] 'agreement among raft nodes before linearized reading' (duration: 535.276033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:33.937245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:33.236456Z","time spent":"700.681968ms","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:42:33.937278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:33.401877Z","time spent":"535.391546ms","remote":"127.0.0.1:43738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:42:33.937566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.237807ms","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-04-09T22:42:33.937622Z","caller":"traceutil/trace.go:171","msg":"trace[495024690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7314; }","duration":"394.293998ms","start":"2026-04-09T22:42:33.543315Z","end":"2026-04-09T22:42:33.937609Z","steps":["trace[495024690] 'agreement among raft nodes before linearized reading' (duration: 394.143954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:33.937655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:33.543296Z","time spent":"394.351429ms","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T22:42:33.938085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.146557ms","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-04-09T22:42:33.938171Z","caller":"traceutil/trace.go:171","msg":"trace[2099239672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7314; }","duration":"303.293691ms","start":"2026-04-09T22:42:33.634862Z","end":"2026-04-09T22:42:33.938155Z","steps":["trace[2099239672] 'agreement among raft nodes before linearized reading' (duration: 303.073305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:33.938206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:33.634835Z","time spent":"303.362663ms","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:42:33.93843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.968249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tjwvd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-09T22:42:33.938481Z","caller":"traceutil/trace.go:171","msg":"trace[1520315221] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tjwvd; range_end:; response_count:1; response_revision:7314; }","duration":"110.068071ms","start":"2026-04-09T22:42:33.828401Z","end":"2026-04-09T22:42:33.938469Z","steps":["trace[1520315221] 'agreement among raft nodes before linearized reading' (duration: 109.937067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:44:31.047249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.245166ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-09T22:44:31.04737Z","caller":"traceutil/trace.go:171","msg":"trace[1440457468] linearizableReadLoop","detail":"{readStateIndex:8530; appliedIndex:8529; }","duration":"254.082709ms","start":"2026-04-09T22:44:30.793264Z","end":"2026-04-09T22:44:31.047347Z","steps":["trace[1440457468] 'read index received' (duration: 37.971µs)","trace[1440457468] 'applied index is now lower than readState.Index' (duration: 254.042918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:44:31.047798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.530141ms","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-09T22:44:31.047842Z","caller":"traceutil/trace.go:171","msg":"trace[222529327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8239; }","duration":"254.611623ms","start":"2026-04-09T22:44:30.793218Z","end":"2026-04-09T22:44:31.04783Z","steps":["trace[222529327] 'agreement among raft nodes before linearized reading' (duration: 254.458249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:44:31.047848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.059725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1984"} {"level":"info","ts":"2026-04-09T22:44:31.047953Z","caller":"traceutil/trace.go:171","msg":"trace[1899130219] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8239; }","duration":"243.205299ms","start":"2026-04-09T22:44:30.804721Z","end":"2026-04-09T22:44:31.047927Z","steps":["trace[1899130219] 'agreement among raft nodes before linearized reading' (duration: 243.006783ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:44:42.472947Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6133} {"level":"info","ts":"2026-04-09T22:44:42.572862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6133,"took":"97.850096ms","hash":3828046503,"current-db-size-bytes":58077184,"current-db-size":"58 MB","current-db-size-in-use-bytes":23142400,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-09T22:44:42.572937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3828046503,"revision":6133,"compact-revision":3024} {"level":"info","ts":"2026-04-09T22:45:00.747033Z","caller":"traceutil/trace.go:171","msg":"trace[1448049038] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"251.541619ms","start":"2026-04-09T22:45:00.495437Z","end":"2026-04-09T22:45:00.746979Z","steps":["trace[1448049038] 'process raft request' (duration: 251.259411ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:45:14.367602Z","caller":"traceutil/trace.go:171","msg":"trace[1272172199] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"158.890226ms","start":"2026-04-09T22:45:14.20867Z","end":"2026-04-09T22:45:14.36756Z","steps":["trace[1272172199] 'process raft request' (duration: 123.566113ms)","trace[1272172199] 'compare' (duration: 34.863291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:45:14.713074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.860102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:45:14.713163Z","caller":"traceutil/trace.go:171","msg":"trace[1404336713] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8570; }","duration":"246.040917ms","start":"2026-04-09T22:45:14.467101Z","end":"2026-04-09T22:45:14.713142Z","steps":["trace[1404336713] 'count revisions from in-memory index tree' (duration: 245.757479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:34.171975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.314531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:45:34.172071Z","caller":"traceutil/trace.go:171","msg":"trace[2029250971] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8796; }","duration":"288.471695ms","start":"2026-04-09T22:45:33.883578Z","end":"2026-04-09T22:45:34.17205Z","steps":["trace[2029250971] 'count revisions from in-memory index tree' (duration: 288.198598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:34.17244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.331719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:45:34.172481Z","caller":"traceutil/trace.go:171","msg":"trace[1859762196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8796; }","duration":"216.409611ms","start":"2026-04-09T22:45:33.956061Z","end":"2026-04-09T22:45:34.172471Z","steps":["trace[1859762196] 'range keys from in-memory index tree' (duration: 216.264757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:34.172917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.166034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-663d26fa-f94b-4d65-8014-1d37f33d77c7\" ","response":"range_response_count:1 size:3933"} {"level":"info","ts":"2026-04-09T22:45:34.173025Z","caller":"traceutil/trace.go:171","msg":"trace[1068165324] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-663d26fa-f94b-4d65-8014-1d37f33d77c7; range_end:; response_count:1; response_revision:8796; }","duration":"110.324729ms","start":"2026-04-09T22:45:34.062675Z","end":"2026-04-09T22:45:34.173Z","steps":["trace[1068165324] 'range keys from in-memory index tree' (duration: 110.003469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.750871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.549827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-663d26fa-f94b-4d65-8014-1d37f33d77c7\" ","response":"range_response_count:1 size:3948"} {"level":"info","ts":"2026-04-09T22:45:35.751017Z","caller":"traceutil/trace.go:171","msg":"trace[462781092] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-663d26fa-f94b-4d65-8014-1d37f33d77c7; range_end:; response_count:1; response_revision:8800; }","duration":"292.724242ms","start":"2026-04-09T22:45:35.458257Z","end":"2026-04-09T22:45:35.750981Z","steps":["trace[462781092] 'range keys from in-memory index tree' (duration: 292.377242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.752107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.835902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tjwvd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-09T22:45:35.752172Z","caller":"traceutil/trace.go:171","msg":"trace[1640479696] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tjwvd; range_end:; response_count:1; response_revision:8800; }","duration":"293.976256ms","start":"2026-04-09T22:45:35.458178Z","end":"2026-04-09T22:45:35.752154Z","steps":["trace[1640479696] 'range keys from in-memory index tree' (duration: 293.662308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.753103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.465779ms","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-09T22:45:35.753175Z","caller":"traceutil/trace.go:171","msg":"trace[1732204466] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8800; }","duration":"294.541231ms","start":"2026-04-09T22:45:35.458614Z","end":"2026-04-09T22:45:35.753155Z","steps":["trace[1732204466] 'range keys from in-memory index tree' (duration: 294.383188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.753688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.071196ms","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-09T22:45:35.753758Z","caller":"traceutil/trace.go:171","msg":"trace[1549806513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8800; }","duration":"295.19019ms","start":"2026-04-09T22:45:35.458552Z","end":"2026-04-09T22:45:35.753742Z","steps":["trace[1549806513] 'range keys from in-memory index tree' (duration: 294.899872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.754235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.56431ms","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-09T22:45:35.754298Z","caller":"traceutil/trace.go:171","msg":"trace[1257107271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8800; }","duration":"295.629081ms","start":"2026-04-09T22:45:35.458655Z","end":"2026-04-09T22:45:35.754284Z","steps":["trace[1257107271] 'range keys from in-memory index tree' (duration: 295.475047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.754749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.033353ms","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-04-09T22:45:35.754813Z","caller":"traceutil/trace.go:171","msg":"trace[88538857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8800; }","duration":"296.098355ms","start":"2026-04-09T22:45:35.458699Z","end":"2026-04-09T22:45:35.754797Z","steps":["trace[88538857] 'range keys from in-memory index tree' (duration: 295.95184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.756022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.272337ms","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-04-09T22:45:35.756093Z","caller":"traceutil/trace.go:171","msg":"trace[449755182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8800; }","duration":"297.37037ms","start":"2026-04-09T22:45:35.458706Z","end":"2026-04-09T22:45:35.756076Z","steps":["trace[449755182] 'range keys from in-memory index tree' (duration: 297.148223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.756569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.708399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:45:35.756636Z","caller":"traceutil/trace.go:171","msg":"trace[1805807611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8800; }","duration":"297.787681ms","start":"2026-04-09T22:45:35.458832Z","end":"2026-04-09T22:45:35.756619Z","steps":["trace[1805807611] 'range keys from in-memory index tree' (duration: 297.650448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.757039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.312663ms","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-09T22:45:35.757102Z","caller":"traceutil/trace.go:171","msg":"trace[155568674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8800; }","duration":"295.376185ms","start":"2026-04-09T22:45:35.461711Z","end":"2026-04-09T22:45:35.757087Z","steps":["trace[155568674] 'range keys from in-memory index tree' (duration: 295.186129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.757443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.714634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:45:35.757509Z","caller":"traceutil/trace.go:171","msg":"trace[2042328877] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8800; }","duration":"295.804927ms","start":"2026-04-09T22:45:35.461688Z","end":"2026-04-09T22:45:35.757493Z","steps":["trace[2042328877] 'count revisions from in-memory index tree' (duration: 295.639492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.758252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.556068ms","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-09T22:45:35.758428Z","caller":"traceutil/trace.go:171","msg":"trace[942402049] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8800; }","duration":"137.678592ms","start":"2026-04-09T22:45:35.620679Z","end":"2026-04-09T22:45:35.758357Z","steps":["trace[942402049] 'range keys from in-memory index tree' (duration: 137.544558ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:46:07.934577Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024cc.wal"} {"level":"info","ts":"2026-04-09T22:46:18.135298Z","caller":"traceutil/trace.go:171","msg":"trace[1188603408] transaction","detail":"{read_only:false; response_revision:9238; number_of_response:1; }","duration":"113.961969ms","start":"2026-04-09T22:46:18.021311Z","end":"2026-04-09T22:46:18.135273Z","steps":["trace[1188603408] 'process raft request' (duration: 113.798874ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:47:17.894767Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T22:47:17.900674Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T22:47:17.900773Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T22:48:48.924833Z","caller":"traceutil/trace.go:171","msg":"trace[1230473489] transaction","detail":"{read_only:false; response_revision:10363; number_of_response:1; }","duration":"160.949633ms","start":"2026-04-09T22:48:48.763835Z","end":"2026-04-09T22:48:48.924785Z","steps":["trace[1230473489] 'process raft request' (duration: 95.972743ms)","trace[1230473489] 'compare' (duration: 64.754434ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:48:48.92492Z","caller":"traceutil/trace.go:171","msg":"trace[888135765] transaction","detail":"{read_only:false; response_revision:10364; number_of_response:1; }","duration":"156.385057ms","start":"2026-04-09T22:48:48.768516Z","end":"2026-04-09T22:48:48.924901Z","steps":["trace[888135765] 'process raft request' (duration: 156.195282ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:48:48.924834Z","caller":"traceutil/trace.go:171","msg":"trace[263309909] linearizableReadLoop","detail":"{readStateIndex:10724; appliedIndex:10723; }","duration":"160.565903ms","start":"2026-04-09T22:48:48.76424Z","end":"2026-04-09T22:48:48.924805Z","steps":["trace[263309909] 'read index received' (duration: 95.590823ms)","trace[263309909] 'applied index is now lower than readState.Index' (duration: 64.97226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:48:48.925186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.919552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-db-init-kmnp4\" ","response":"range_response_count:1 size:7349"} {"level":"info","ts":"2026-04-09T22:48:48.925238Z","caller":"traceutil/trace.go:171","msg":"trace[556088551] range","detail":"{range_begin:/registry/pods/openstack/staffeln-db-init-kmnp4; range_end:; response_count:1; response_revision:10364; }","duration":"160.986144ms","start":"2026-04-09T22:48:48.764236Z","end":"2026-04-09T22:48:48.925223Z","steps":["trace[556088551] 'agreement among raft nodes before linearized reading' (duration: 160.604984ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:49:42.479465Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8306} {"level":"info","ts":"2026-04-09T22:49:42.562824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8306,"took":"79.900431ms","hash":2978629607,"current-db-size-bytes":58077184,"current-db-size":"58 MB","current-db-size-in-use-bytes":24850432,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-09T22:49:42.562915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2978629607,"revision":8306,"compact-revision":6133} {"level":"info","ts":"2026-04-09T22:54:42.484829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10692} {"level":"info","ts":"2026-04-09T22:54:42.559443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10692,"took":"72.344503ms","hash":1122766350,"current-db-size-bytes":58077184,"current-db-size":"58 MB","current-db-size-in-use-bytes":20332544,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-09T22:54:42.559508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1122766350,"revision":10692,"compact-revision":8306}