{"level":"warn","ts":"2026-03-14T01:32:34.210173Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T01:32:34.210253Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-03-14T01:32:34.210329Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T01:32:34.210343Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-03-14T01:32:34.210373Z","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-03-14T01:32:34.211029Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-03-14T01:32:34.211135Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-03-14T01:32:34.214935Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.571268ms"} {"level":"info","ts":"2026-03-14T01:32:34.226278Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-03-14T01:32:34.22641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T01:32:34.22645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-03-14T01:32:34.226476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T01:32:34.226494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-03-14T01:32:34.226554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-03-14T01:32:34.232002Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T01:32:34.234024Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T01:32:34.235707Z","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-03-14T01:32:34.238972Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T01:32:34.239137Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T01:32:34.23919Z","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-03-14T01:32:34.239265Z","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-03-14T01:32:34.239287Z","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-03-14T01:32:34.241729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-03-14T01:32:34.242182Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-03-14T01:32:34.244225Z","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-03-14T01:32:34.244358Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-03-14T01:32:34.24439Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-03-14T01:32:34.244582Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T01:32:34.24465Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T01:32:35.12749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T01:32:35.127531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T01:32:35.127561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-03-14T01:32:35.127577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-03-14T01:32:35.127582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-03-14T01:32:35.127593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-03-14T01:32:35.1276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-03-14T01:32:35.128438Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:32:35.129253Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T01:32:35.12946Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:32:35.129537Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:32:35.129617Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:32:35.129748Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:32:35.129787Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:32:35.13182Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T01:32:35.131859Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T01:32:35.132486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T01:32:35.136361Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-03-14T01:33:01.492443Z","caller":"traceutil/trace.go:171","msg":"trace[2130488686] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"100.854589ms","start":"2026-03-14T01:33:01.391561Z","end":"2026-03-14T01:33:01.492415Z","steps":["trace[2130488686] 'process raft request' (duration: 100.542841ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:09.254415Z","caller":"traceutil/trace.go:171","msg":"trace[134903108] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"222.571926ms","start":"2026-03-14T01:33:09.031814Z","end":"2026-03-14T01:33:09.254386Z","steps":["trace[134903108] 'process raft request' (duration: 222.345359ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:21.373698Z","caller":"traceutil/trace.go:171","msg":"trace[1049090633] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"244.75167ms","start":"2026-03-14T01:34:21.128897Z","end":"2026-03-14T01:34:21.373648Z","steps":["trace[1049090633] 'process raft request' (duration: 234.524258ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:21.506576Z","caller":"traceutil/trace.go:171","msg":"trace[724393140] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"125.433064ms","start":"2026-03-14T01:34:21.381124Z","end":"2026-03-14T01:34:21.506557Z","steps":["trace[724393140] 'process raft request' (duration: 125.382902ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:21.506604Z","caller":"traceutil/trace.go:171","msg":"trace[2098333325] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"127.872519ms","start":"2026-03-14T01:34:21.378707Z","end":"2026-03-14T01:34:21.50658Z","steps":["trace[2098333325] 'process raft request' (duration: 118.902693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:21.780885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.189467ms","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-03-14T01:34:21.781013Z","caller":"traceutil/trace.go:171","msg":"trace[1184598940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1540; }","duration":"168.412953ms","start":"2026-03-14T01:34:21.612579Z","end":"2026-03-14T01:34:21.780992Z","steps":["trace[1184598940] 'range keys from in-memory index tree' (duration: 167.93232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:21.78148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.012882ms","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-03-14T01:34:21.781575Z","caller":"traceutil/trace.go:171","msg":"trace[1799615364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1540; }","duration":"152.089665ms","start":"2026-03-14T01:34:21.629427Z","end":"2026-03-14T01:34:21.781517Z","steps":["trace[1799615364] 'range keys from in-memory index tree' (duration: 151.873009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:21.781474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.801078ms","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-03-14T01:34:21.781719Z","caller":"traceutil/trace.go:171","msg":"trace[356395274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1540; }","duration":"136.103065ms","start":"2026-03-14T01:34:21.645583Z","end":"2026-03-14T01:34:21.781686Z","steps":["trace[356395274] 'range keys from in-memory index tree' (duration: 135.549351ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:05.321403Z","caller":"traceutil/trace.go:171","msg":"trace[1610161794] linearizableReadLoop","detail":"{readStateIndex:1883; appliedIndex:1882; }","duration":"115.090198ms","start":"2026-03-14T01:35:05.20627Z","end":"2026-03-14T01:35:05.32136Z","steps":["trace[1610161794] 'read index received' (duration: 114.873341ms)","trace[1610161794] 'applied index is now lower than readState.Index' (duration: 215.497µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:35:05.321454Z","caller":"traceutil/trace.go:171","msg":"trace[180241837] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"119.436077ms","start":"2026-03-14T01:35:05.201973Z","end":"2026-03-14T01:35:05.321409Z","steps":["trace[180241837] 'process raft request' (duration: 119.191869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:05.321777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.468708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189c9147a6532075\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-14T01:35:05.321878Z","caller":"traceutil/trace.go:171","msg":"trace[611077197] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.189c9147a6532075; range_end:; response_count:1; response_revision:1809; }","duration":"115.645744ms","start":"2026-03-14T01:35:05.206206Z","end":"2026-03-14T01:35:05.321852Z","steps":["trace[611077197] 'agreement among raft nodes before linearized reading' (duration: 115.282814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:05.608118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.682352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:35:05.608267Z","caller":"traceutil/trace.go:171","msg":"trace[128424839] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"283.838107ms","start":"2026-03-14T01:35:05.324407Z","end":"2026-03-14T01:35:05.608245Z","steps":["trace[128424839] 'process raft request' (duration: 61.313862ms)","trace[128424839] 'compare' (duration: 221.304632ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:35:05.79663Z","caller":"traceutil/trace.go:171","msg":"trace[1065610756] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"172.598415ms","start":"2026-03-14T01:35:05.624005Z","end":"2026-03-14T01:35:05.796603Z","steps":["trace[1065610756] 'process raft request' (duration: 133.572154ms)","trace[1065610756] 'compare' (duration: 38.814165ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:35:05.796627Z","caller":"traceutil/trace.go:171","msg":"trace[1135715913] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1884; }","duration":"155.26139ms","start":"2026-03-14T01:35:05.64133Z","end":"2026-03-14T01:35:05.796591Z","steps":["trace[1135715913] 'read index received' (duration: 51.631076ms)","trace[1135715913] 'applied index is now lower than readState.Index' (duration: 103.628273ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:35:05.797063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.710931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-03-14T01:35:05.797147Z","caller":"traceutil/trace.go:171","msg":"trace[2043921502] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1812; }","duration":"155.850455ms","start":"2026-03-14T01:35:05.641272Z","end":"2026-03-14T01:35:05.797122Z","steps":["trace[2043921502] 'agreement among raft nodes before linearized reading' (duration: 155.568487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:12.454064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.368544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:35:12.454153Z","caller":"traceutil/trace.go:171","msg":"trace[214006551] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1844; }","duration":"178.515368ms","start":"2026-03-14T01:35:12.275617Z","end":"2026-03-14T01:35:12.454132Z","steps":["trace[214006551] 'count revisions from in-memory index tree' (duration: 178.259921ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:17.964784Z","caller":"traceutil/trace.go:171","msg":"trace[2056523885] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"109.074572ms","start":"2026-03-14T01:35:17.855672Z","end":"2026-03-14T01:35:17.964746Z","steps":["trace[2056523885] 'process raft request' (duration: 108.849546ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:36:41.761703Z","caller":"traceutil/trace.go:171","msg":"trace[925563198] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"126.508531ms","start":"2026-03-14T01:36:41.635166Z","end":"2026-03-14T01:36:41.761675Z","steps":["trace[925563198] 'process raft request' (duration: 126.354167ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:36:41.913175Z","caller":"traceutil/trace.go:171","msg":"trace[1984787451] transaction","detail":"{read_only:false; response_revision:2477; number_of_response:1; }","duration":"198.738966ms","start":"2026-03-14T01:36:41.714257Z","end":"2026-03-14T01:36:41.912996Z","steps":["trace[1984787451] 'process raft request' (duration: 189.840658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:40:30.900528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.688279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:40:30.900651Z","caller":"traceutil/trace.go:171","msg":"trace[1367923212] transaction","detail":"{read_only:false; response_revision:4368; number_of_response:1; }","duration":"202.946803ms","start":"2026-03-14T01:40:30.697688Z","end":"2026-03-14T01:40:30.900635Z","steps":["trace[1367923212] 'process raft request' (duration: 87.060588ms)","trace[1367923212] 'compare' (duration: 115.413572ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:40:30.910083Z","caller":"traceutil/trace.go:171","msg":"trace[887850817] linearizableReadLoop","detail":"{readStateIndex:4545; appliedIndex:4543; }","duration":"199.823287ms","start":"2026-03-14T01:40:30.710232Z","end":"2026-03-14T01:40:30.910055Z","steps":["trace[887850817] 'read index received' (duration: 74.47481ms)","trace[887850817] 'applied index is now lower than readState.Index' (duration: 125.347227ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:40:30.910135Z","caller":"traceutil/trace.go:171","msg":"trace[974994502] transaction","detail":"{read_only:false; response_revision:4369; number_of_response:1; }","duration":"208.436105ms","start":"2026-03-14T01:40:30.701662Z","end":"2026-03-14T01:40:30.910099Z","steps":["trace[974994502] 'process raft request' (duration: 208.149528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:40:30.910395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.132415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-03-14T01:40:30.910494Z","caller":"traceutil/trace.go:171","msg":"trace[1848143168] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4369; }","duration":"200.276699ms","start":"2026-03-14T01:40:30.710191Z","end":"2026-03-14T01:40:30.910468Z","steps":["trace[1848143168] 'agreement among raft nodes before linearized reading' (duration: 200.006622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:40:30.910731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.534566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33ac00b1-86e9-4c05-80a8-96c658444000\" ","response":"range_response_count:1 size:3774"} {"level":"warn","ts":"2026-03-14T01:40:30.910829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.17808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nq9fl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-14T01:40:30.910928Z","caller":"traceutil/trace.go:171","msg":"trace[1001158328] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nq9fl; range_end:; response_count:1; response_revision:4369; }","duration":"113.301193ms","start":"2026-03-14T01:40:30.797601Z","end":"2026-03-14T01:40:30.910903Z","steps":["trace[1001158328] 'agreement among raft nodes before linearized reading' (duration: 113.033296ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:40:30.910898Z","caller":"traceutil/trace.go:171","msg":"trace[2055014327] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33ac00b1-86e9-4c05-80a8-96c658444000; range_end:; response_count:1; response_revision:4369; }","duration":"115.758501ms","start":"2026-03-14T01:40:30.795076Z","end":"2026-03-14T01:40:30.910834Z","steps":["trace[2055014327] 'agreement among raft nodes before linearized reading' (duration: 115.254368ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:40:32.514266Z","caller":"traceutil/trace.go:171","msg":"trace[395687296] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"232.572552ms","start":"2026-03-14T01:40:32.281667Z","end":"2026-03-14T01:40:32.51424Z","steps":["trace[395687296] 'process raft request' (duration: 232.415778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:41:03.674669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.749184ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-14T01:41:03.674785Z","caller":"traceutil/trace.go:171","msg":"trace[22534801] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5239; }","duration":"146.202301ms","start":"2026-03-14T01:41:03.528564Z","end":"2026-03-14T01:41:03.674767Z","steps":["trace[22534801] 'read index received' (duration: 37.292883ms)","trace[22534801] 'applied index is now lower than readState.Index' (duration: 108.907858ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:41:03.675016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.448828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-03-14T01:41:03.675056Z","caller":"traceutil/trace.go:171","msg":"trace[1639448979] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5046; }","duration":"146.54577ms","start":"2026-03-14T01:41:03.5285Z","end":"2026-03-14T01:41:03.675046Z","steps":["trace[1639448979] 'agreement among raft nodes before linearized reading' (duration: 146.375936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:41:03.675266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.895229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2101"} {"level":"info","ts":"2026-03-14T01:41:03.675406Z","caller":"traceutil/trace.go:171","msg":"trace[333441759] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5046; }","duration":"134.077524ms","start":"2026-03-14T01:41:03.541301Z","end":"2026-03-14T01:41:03.675378Z","steps":["trace[333441759] 'agreement among raft nodes before linearized reading' (duration: 133.663443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:41:03.675545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.661574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-03-14T01:41:03.675658Z","caller":"traceutil/trace.go:171","msg":"trace[287818058] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5046; }","duration":"123.824878ms","start":"2026-03-14T01:41:03.551815Z","end":"2026-03-14T01:41:03.67564Z","steps":["trace[287818058] 'agreement among raft nodes before linearized reading' (duration: 123.328605ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:41:03.852464Z","caller":"traceutil/trace.go:171","msg":"trace[1041864644] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"103.04295ms","start":"2026-03-14T01:41:03.749374Z","end":"2026-03-14T01:41:03.852417Z","steps":["trace[1041864644] 'process raft request' (duration: 67.419972ms)","trace[1041864644] 'compare' (duration: 35.115684ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:41:03.86156Z","caller":"traceutil/trace.go:171","msg":"trace[206473785] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"111.040945ms","start":"2026-03-14T01:41:03.750487Z","end":"2026-03-14T01:41:03.861528Z","steps":["trace[206473785] 'process raft request' (duration: 110.742097ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:41:03.861867Z","caller":"traceutil/trace.go:171","msg":"trace[1056152810] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"111.27097ms","start":"2026-03-14T01:41:03.750575Z","end":"2026-03-14T01:41:03.861846Z","steps":["trace[1056152810] 'process raft request' (duration: 110.870799ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:41:03.862132Z","caller":"traceutil/trace.go:171","msg":"trace[1842246085] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"110.424978ms","start":"2026-03-14T01:41:03.751694Z","end":"2026-03-14T01:41:03.862119Z","steps":["trace[1842246085] 'process raft request' (duration: 110.062759ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:42:35.160773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2743} {"level":"info","ts":"2026-03-14T01:42:35.21927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2743,"took":"57.007162ms","hash":2894759829,"current-db-size-bytes":41406464,"current-db-size":"41 MB","current-db-size-in-use-bytes":31162368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-14T01:42:35.219323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2894759829,"revision":2743,"compact-revision":-1} {"level":"info","ts":"2026-03-14T01:46:55.896641Z","caller":"traceutil/trace.go:171","msg":"trace[1558470386] transaction","detail":"{read_only:false; response_revision:7554; number_of_response:1; }","duration":"189.66204ms","start":"2026-03-14T01:46:55.70696Z","end":"2026-03-14T01:46:55.896622Z","steps":["trace[1558470386] 'process raft request' (duration: 189.520066ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:46:56.163943Z","caller":"traceutil/trace.go:171","msg":"trace[1849695319] transaction","detail":"{read_only:false; response_revision:7555; number_of_response:1; }","duration":"455.352754ms","start":"2026-03-14T01:46:55.708554Z","end":"2026-03-14T01:46:56.163907Z","steps":["trace[1849695319] 'process raft request' (duration: 426.125852ms)","trace[1849695319] 'compare' (duration: 28.716229ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:46:56.163975Z","caller":"traceutil/trace.go:171","msg":"trace[1062755540] transaction","detail":"{read_only:false; response_revision:7556; number_of_response:1; }","duration":"312.916699ms","start":"2026-03-14T01:46:55.851038Z","end":"2026-03-14T01:46:56.163955Z","steps":["trace[1062755540] 'process raft request' (duration: 312.755895ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:46:56.163934Z","caller":"traceutil/trace.go:171","msg":"trace[977961350] linearizableReadLoop","detail":"{readStateIndex:7833; appliedIndex:7831; }","duration":"452.710043ms","start":"2026-03-14T01:46:55.711196Z","end":"2026-03-14T01:46:56.163906Z","steps":["trace[977961350] 'read index received' (duration: 185.363314ms)","trace[977961350] 'applied index is now lower than readState.Index' (duration: 267.345539ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:46:56.164116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:46:55.708532Z","time spent":"455.491817ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:46:56.164157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.93314ms","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-03-14T01:46:56.164223Z","caller":"traceutil/trace.go:171","msg":"trace[700252849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7556; }","duration":"453.038132ms","start":"2026-03-14T01:46:55.711164Z","end":"2026-03-14T01:46:56.164202Z","steps":["trace[700252849] 'agreement among raft nodes before linearized reading' (duration: 452.840887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:46:56.164262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:46:55.711152Z","time spent":"453.101823ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T01:46:56.164263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.131761ms","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-03-14T01:46:56.164302Z","caller":"traceutil/trace.go:171","msg":"trace[1110941866] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7556; }","duration":"448.181792ms","start":"2026-03-14T01:46:55.716107Z","end":"2026-03-14T01:46:56.164289Z","steps":["trace[1110941866] 'agreement among raft nodes before linearized reading' (duration: 448.020818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:46:56.164334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:46:55.716099Z","time spent":"448.226223ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-14T01:46:56.164459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.88955ms","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-03-14T01:46:56.16449Z","caller":"traceutil/trace.go:171","msg":"trace[1582693271] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:7556; }","duration":"203.919941ms","start":"2026-03-14T01:46:55.960558Z","end":"2026-03-14T01:46:56.164478Z","steps":["trace[1582693271] 'agreement among raft nodes before linearized reading' (duration: 203.8663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:46:56.164525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:46:55.851024Z","time spent":"313.001932ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:46:56.16459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.644768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:46:56.164729Z","caller":"traceutil/trace.go:171","msg":"trace[981285944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7556; }","duration":"320.850543ms","start":"2026-03-14T01:46:55.843851Z","end":"2026-03-14T01:46:56.164702Z","steps":["trace[981285944] 'agreement among raft nodes before linearized reading' (duration: 320.616167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:46:56.164783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:46:55.843833Z","time spent":"320.939855ms","remote":"127.0.0.1:37712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T01:46:56.164839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.220662ms","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-03-14T01:46:56.164974Z","caller":"traceutil/trace.go:171","msg":"trace[408436843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7556; }","duration":"439.428158ms","start":"2026-03-14T01:46:55.725518Z","end":"2026-03-14T01:46:56.164947Z","steps":["trace[408436843] 'agreement among raft nodes before linearized reading' (duration: 438.973336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:46:56.165025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:46:55.725498Z","time spent":"439.51724ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-14T01:46:56.348149Z","caller":"traceutil/trace.go:171","msg":"trace[930301266] transaction","detail":"{read_only:false; response_revision:7560; number_of_response:1; }","duration":"103.871351ms","start":"2026-03-14T01:46:56.244241Z","end":"2026-03-14T01:46:56.348112Z","steps":["trace[930301266] 'process raft request' (duration: 103.656756ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:46:58.069002Z","caller":"traceutil/trace.go:171","msg":"trace[596048005] transaction","detail":"{read_only:false; response_revision:7565; number_of_response:1; }","duration":"166.633742ms","start":"2026-03-14T01:46:57.902338Z","end":"2026-03-14T01:46:58.068972Z","steps":["trace[596048005] 'process raft request' (duration: 126.838606ms)","trace[596048005] 'compare' (duration: 39.608821ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:47:28.314782Z","caller":"traceutil/trace.go:171","msg":"trace[1898258786] transaction","detail":"{read_only:false; response_revision:7767; number_of_response:1; }","duration":"101.851157ms","start":"2026-03-14T01:47:28.212902Z","end":"2026-03-14T01:47:28.314753Z","steps":["trace[1898258786] 'process raft request' (duration: 101.623321ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:47:35.168221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5775} {"level":"info","ts":"2026-03-14T01:47:35.261891Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5775,"took":"91.615494ms","hash":1332763600,"current-db-size-bytes":42532864,"current-db-size":"42 MB","current-db-size-in-use-bytes":23040000,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-14T01:47:35.261969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1332763600,"revision":5775,"compact-revision":2743} {"level":"info","ts":"2026-03-14T01:47:37.504762Z","caller":"traceutil/trace.go:171","msg":"trace[723536516] transaction","detail":"{read_only:false; response_revision:7849; number_of_response:1; }","duration":"149.798592ms","start":"2026-03-14T01:47:37.354925Z","end":"2026-03-14T01:47:37.504724Z","steps":["trace[723536516] 'process raft request' (duration: 99.563307ms)","trace[723536516] 'compare' (duration: 50.04016ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:47:37.504762Z","caller":"traceutil/trace.go:171","msg":"trace[608227301] linearizableReadLoop","detail":"{readStateIndex:8142; appliedIndex:8141; }","duration":"149.277288ms","start":"2026-03-14T01:47:37.355443Z","end":"2026-03-14T01:47:37.50472Z","steps":["trace[608227301] 'read index received' (duration: 98.798606ms)","trace[608227301] 'applied index is now lower than readState.Index' (duration: 50.475832ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:47:37.505186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.718019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-14T01:47:37.505252Z","caller":"traceutil/trace.go:171","msg":"trace[220109400] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:7850; }","duration":"149.809163ms","start":"2026-03-14T01:47:37.355426Z","end":"2026-03-14T01:47:37.505235Z","steps":["trace[220109400] 'agreement among raft nodes before linearized reading' (duration: 149.437243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:47:38.479023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.994624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-14T01:47:38.4792Z","caller":"traceutil/trace.go:171","msg":"trace[1301869966] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:7860; }","duration":"163.20045ms","start":"2026-03-14T01:47:38.315974Z","end":"2026-03-14T01:47:38.479174Z","steps":["trace[1301869966] 'agreement among raft nodes before linearized reading' (duration: 162.907262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:47:38.479025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.519171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-14T01:47:38.479336Z","caller":"traceutil/trace.go:171","msg":"trace[759334609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7860; }","duration":"122.844629ms","start":"2026-03-14T01:47:38.356459Z","end":"2026-03-14T01:47:38.479304Z","steps":["trace[759334609] 'agreement among raft nodes before linearized reading' (duration: 122.420418ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:47:38.479002Z","caller":"traceutil/trace.go:171","msg":"trace[1078739721] transaction","detail":"{read_only:false; response_revision:7860; number_of_response:1; }","duration":"125.707347ms","start":"2026-03-14T01:47:38.353238Z","end":"2026-03-14T01:47:38.478945Z","steps":["trace[1078739721] 'process raft request' (duration: 125.465681ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:47:38.593418Z","caller":"traceutil/trace.go:171","msg":"trace[1353605915] transaction","detail":"{read_only:false; response_revision:7862; number_of_response:1; }","duration":"100.295706ms","start":"2026-03-14T01:47:38.493089Z","end":"2026-03-14T01:47:38.593384Z","steps":["trace[1353605915] 'process raft request' (duration: 68.822673ms)","trace[1353605915] 'compare' (duration: 31.222656ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:47:39.223616Z","caller":"traceutil/trace.go:171","msg":"trace[739165053] transaction","detail":"{read_only:false; response_revision:7870; number_of_response:1; }","duration":"142.25635ms","start":"2026-03-14T01:47:39.081325Z","end":"2026-03-14T01:47:39.223581Z","steps":["trace[739165053] 'process raft request' (duration: 121.903565ms)","trace[739165053] 'compare' (duration: 20.128459ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:48:52.962255Z","caller":"traceutil/trace.go:171","msg":"trace[1536160153] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"100.268785ms","start":"2026-03-14T01:48:52.861957Z","end":"2026-03-14T01:48:52.962226Z","steps":["trace[1536160153] 'process raft request' (duration: 70.050177ms)","trace[1536160153] 'compare' (duration: 29.91707ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:51:12.071737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.06107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b3328576-4e56-45a1-8363-025a1f5a7863\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-03-14T01:51:12.071828Z","caller":"traceutil/trace.go:171","msg":"trace[248843447] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b3328576-4e56-45a1-8363-025a1f5a7863; range_end:; response_count:1; response_revision:9555; }","duration":"101.185633ms","start":"2026-03-14T01:51:11.970624Z","end":"2026-03-14T01:51:12.07181Z","steps":["trace[248843447] 'range keys from in-memory index tree' (duration: 100.939287ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:51:25.707384Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-14T01:51:25.713262Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-14T01:51:25.713378Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-14T01:52:35.174851Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7833} {"level":"info","ts":"2026-03-14T01:52:35.248045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7833,"took":"70.796695ms","hash":3924000197,"current-db-size-bytes":42532864,"current-db-size":"42 MB","current-db-size-in-use-bytes":25878528,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-14T01:52:35.248172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3924000197,"revision":7833,"compact-revision":5775} {"level":"info","ts":"2026-03-14T01:57:35.181763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9998} {"level":"info","ts":"2026-03-14T01:57:35.2533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9998,"took":"69.004406ms","hash":848475517,"current-db-size-bytes":42532864,"current-db-size":"42 MB","current-db-size-in-use-bytes":16654336,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-14T01:57:35.253375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":848475517,"revision":9998,"compact-revision":7833}