{"level":"warn","ts":"2026-05-12T16:28:54.772197Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T16:28:54.772278Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.9:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.9:2380","--initial-cluster=instance=https://199.19.213.9:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.9:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.9:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-12T16:28:54.772339Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T16:28:54.772415Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-05-12T16:28:54.772465Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T16:28:54.773345Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"]} {"level":"info","ts":"2026-05-12T16:28:54.773435Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.9:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-12T16:28:54.777625Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.943809ms"} {"level":"info","ts":"2026-05-12T16:28:54.784488Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3a0c9d65f167b31b","cluster-id":"86e523b598166645"} {"level":"info","ts":"2026-05-12T16:28:54.784653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T16:28:54.784683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 0"} {"level":"info","ts":"2026-05-12T16:28:54.784696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3a0c9d65f167b31b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T16:28:54.78471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 1"} {"level":"info","ts":"2026-05-12T16:28:54.784755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"warn","ts":"2026-05-12T16:28:54.792999Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T16:28:54.794912Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T16:28:54.795938Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-12T16:28:54.798077Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3a0c9d65f167b31b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T16:28:54.798263Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3a0c9d65f167b31b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T16:28:54.798525Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T16:28:54.798692Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T16:28:54.79872Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T16:28:54.801916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"info","ts":"2026-05-12T16:28:54.8035Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","added-peer-id":"3a0c9d65f167b31b","added-peer-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-05-12T16:28:54.804351Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T16:28:54.804481Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-05-12T16:28:54.804537Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-05-12T16:28:54.804694Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T16:28:54.804856Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3a0c9d65f167b31b","initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T16:28:55.085916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T16:28:55.086078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T16:28:55.086114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgPreVoteResp from 3a0c9d65f167b31b at term 1"} {"level":"info","ts":"2026-05-12T16:28:55.086151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became candidate at term 2"} {"level":"info","ts":"2026-05-12T16:28:55.086162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgVoteResp from 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-05-12T16:28:55.086178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became leader at term 2"} {"level":"info","ts":"2026-05-12T16:28:55.086191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3a0c9d65f167b31b elected leader 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-05-12T16:28:55.087485Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:28:55.088352Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3a0c9d65f167b31b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.9:2379]}","request-path":"/0/members/3a0c9d65f167b31b/attributes","cluster-id":"86e523b598166645","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T16:28:55.088499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:28:55.088596Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T16:28:55.088665Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T16:28:55.088702Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:28:55.089031Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:28:55.089128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:28:55.089161Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:28:55.091357Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.9:2379"} {"level":"info","ts":"2026-05-12T16:28:55.091454Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T16:29:01.47087Z","caller":"traceutil/trace.go:171","msg":"trace[1285863835] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"186.990039ms","start":"2026-05-12T16:29:01.283856Z","end":"2026-05-12T16:29:01.470846Z","steps":["trace[1285863835] 'process raft request' (duration: 186.840398ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:29:02.20594Z","caller":"traceutil/trace.go:171","msg":"trace[1708337010] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"620.89592ms","start":"2026-05-12T16:29:01.585027Z","end":"2026-05-12T16:29:02.205923Z","steps":["trace[1708337010] 'process raft request' (duration: 620.79981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:29:02.206256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:29:01.584996Z","time spent":"621.034432ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T16:29:31.098113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.952051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T16:29:31.098249Z","caller":"traceutil/trace.go:171","msg":"trace[1953647227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"287.138702ms","start":"2026-05-12T16:29:30.811089Z","end":"2026-05-12T16:29:31.098228Z","steps":["trace[1953647227] 'range keys from in-memory index tree' (duration: 286.77579ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:31:02.453246Z","caller":"traceutil/trace.go:171","msg":"trace[1398297592] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"242.950975ms","start":"2026-05-12T16:31:02.210268Z","end":"2026-05-12T16:31:02.453219Z","steps":["trace[1398297592] 'process raft request' (duration: 242.796062ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:31:08.115719Z","caller":"traceutil/trace.go:171","msg":"trace[2083919587] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"126.316686ms","start":"2026-05-12T16:31:07.989342Z","end":"2026-05-12T16:31:08.115658Z","steps":["trace[2083919587] 'process raft request' (duration: 126.117572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:31:44.703904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.996132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-05-12T16:31:44.704512Z","caller":"traceutil/trace.go:171","msg":"trace[1696312906] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2168; }","duration":"237.647722ms","start":"2026-05-12T16:31:44.466845Z","end":"2026-05-12T16:31:44.704493Z","steps":["trace[1696312906] 'range keys from in-memory index tree' (duration: 236.796669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:32:27.704027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.07651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-12T16:32:27.704113Z","caller":"traceutil/trace.go:171","msg":"trace[536355162] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2561; }","duration":"167.210642ms","start":"2026-05-12T16:32:27.536883Z","end":"2026-05-12T16:32:27.704094Z","steps":["trace[536355162] 'range keys from in-memory index tree' (duration: 166.864607ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:32:27.704224Z","caller":"traceutil/trace.go:171","msg":"trace[1237973207] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"135.007946ms","start":"2026-05-12T16:32:27.5692Z","end":"2026-05-12T16:32:27.704208Z","steps":["trace[1237973207] 'process raft request' (duration: 134.864373ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:16.064275Z","caller":"traceutil/trace.go:171","msg":"trace[1444720703] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4397; }","duration":"100.508001ms","start":"2026-05-12T16:35:15.963748Z","end":"2026-05-12T16:35:16.064256Z","steps":["trace[1444720703] 'process raft request' (duration: 100.377897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:17.350648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.276641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T16:35:17.350753Z","caller":"traceutil/trace.go:171","msg":"trace[595257674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4415; }","duration":"153.412425ms","start":"2026-05-12T16:35:17.197321Z","end":"2026-05-12T16:35:17.350733Z","steps":["trace[595257674] 'range keys from in-memory index tree' (duration: 153.169407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:17.350648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.214173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T16:35:17.350961Z","caller":"traceutil/trace.go:171","msg":"trace[1731161414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4415; }","duration":"156.572154ms","start":"2026-05-12T16:35:17.194367Z","end":"2026-05-12T16:35:17.350939Z","steps":["trace[1731161414] 'range keys from in-memory index tree' (duration: 156.036687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:23.232894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.955381ms","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-05-12T16:35:23.233063Z","caller":"traceutil/trace.go:171","msg":"trace[1163294857] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4466; }","duration":"143.163007ms","start":"2026-05-12T16:35:23.089875Z","end":"2026-05-12T16:35:23.233038Z","steps":["trace[1163294857] 'range keys from in-memory index tree' (duration: 142.91701ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:23.426164Z","caller":"traceutil/trace.go:171","msg":"trace[2029493859] transaction","detail":"{read_only:false; response_revision:4467; number_of_response:1; }","duration":"187.963711ms","start":"2026-05-12T16:35:23.238172Z","end":"2026-05-12T16:35:23.426135Z","steps":["trace[2029493859] 'process raft request' (duration: 187.726823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:23.616089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.608476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:35:23.616233Z","caller":"traceutil/trace.go:171","msg":"trace[272356594] linearizableReadLoop","detail":"{readStateIndex:4662; appliedIndex:4661; }","duration":"190.124907ms","start":"2026-05-12T16:35:23.426084Z","end":"2026-05-12T16:35:23.616209Z","steps":["trace[272356594] 'read index received' (duration: 70.982035ms)","trace[272356594] 'applied index is now lower than readState.Index' (duration: 119.140872ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:23.616326Z","caller":"traceutil/trace.go:171","msg":"trace[555620898] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"192.288313ms","start":"2026-05-12T16:35:23.424023Z","end":"2026-05-12T16:35:23.616311Z","steps":["trace[555620898] 'process raft request' (duration: 73.025658ms)","trace[555620898] 'compare' (duration: 118.374678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:23.616586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.424295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T16:35:23.616656Z","caller":"traceutil/trace.go:171","msg":"trace[327694532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4468; }","duration":"203.56039ms","start":"2026-05-12T16:35:23.41308Z","end":"2026-05-12T16:35:23.61664Z","steps":["trace[327694532] 'agreement among raft nodes before linearized reading' (duration: 203.24934ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:23.629841Z","caller":"traceutil/trace.go:171","msg":"trace[1158914398] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"198.252306ms","start":"2026-05-12T16:35:23.431539Z","end":"2026-05-12T16:35:23.629792Z","steps":["trace[1158914398] 'process raft request' (duration: 197.977698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:23.629838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.722951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:23.630035Z","caller":"traceutil/trace.go:171","msg":"trace[130253493] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4469; }","duration":"108.01151ms","start":"2026-05-12T16:35:23.522Z","end":"2026-05-12T16:35:23.630012Z","steps":["trace[130253493] 'agreement among raft nodes before linearized reading' (duration: 107.701671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:24.067687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.852872ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T16:35:24.067825Z","caller":"traceutil/trace.go:171","msg":"trace[1217288345] linearizableReadLoop","detail":"{readStateIndex:4668; appliedIndex:4667; }","duration":"214.517567ms","start":"2026-05-12T16:35:23.85329Z","end":"2026-05-12T16:35:24.067807Z","steps":["trace[1217288345] 'read index received' (duration: 68.50635ms)","trace[1217288345] 'applied index is now lower than readState.Index' (duration: 146.009886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:24.068174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.697899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-8564f856cd-v2mlr.18aedea998276434\" ","response":"range_response_count:1 size:898"} {"level":"warn","ts":"2026-05-12T16:35:24.06825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.54226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-12T16:35:24.068293Z","caller":"traceutil/trace.go:171","msg":"trace[2087716404] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4473; }","duration":"187.584451ms","start":"2026-05-12T16:35:23.880697Z","end":"2026-05-12T16:35:24.068281Z","steps":["trace[2087716404] 'agreement among raft nodes before linearized reading' (duration: 187.474448ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:24.068297Z","caller":"traceutil/trace.go:171","msg":"trace[999261484] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-8564f856cd-v2mlr.18aedea998276434; range_end:; response_count:1; response_revision:4473; }","duration":"210.881075ms","start":"2026-05-12T16:35:23.857391Z","end":"2026-05-12T16:35:24.068272Z","steps":["trace[999261484] 'agreement among raft nodes before linearized reading' (duration: 210.544674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:24.068339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.619014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:24.068398Z","caller":"traceutil/trace.go:171","msg":"trace[1889644842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4473; }","duration":"190.734818ms","start":"2026-05-12T16:35:23.87765Z","end":"2026-05-12T16:35:24.068385Z","steps":["trace[1889644842] 'agreement among raft nodes before linearized reading' (duration: 190.639185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:24.068404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.719002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-12T16:35:24.068505Z","caller":"traceutil/trace.go:171","msg":"trace[1473559441] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4473; }","duration":"135.878727ms","start":"2026-05-12T16:35:23.932604Z","end":"2026-05-12T16:35:24.068482Z","steps":["trace[1473559441] 'agreement among raft nodes before linearized reading' (duration: 135.565897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:24.068633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.740414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16229"} {"level":"info","ts":"2026-05-12T16:35:24.068951Z","caller":"traceutil/trace.go:171","msg":"trace[966098255] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4473; }","duration":"215.522407ms","start":"2026-05-12T16:35:23.853232Z","end":"2026-05-12T16:35:24.068755Z","steps":["trace[966098255] 'agreement among raft nodes before linearized reading' (duration: 214.660991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:24.432306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.532461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:35:24.432704Z","caller":"traceutil/trace.go:171","msg":"trace[1965086042] linearizableReadLoop","detail":"{readStateIndex:4671; appliedIndex:4669; }","duration":"307.169925ms","start":"2026-05-12T16:35:24.125514Z","end":"2026-05-12T16:35:24.432684Z","steps":["trace[1965086042] 'read index received' (duration: 123.927293ms)","trace[1965086042] 'applied index is now lower than readState.Index' (duration: 183.241732ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:24.432781Z","caller":"traceutil/trace.go:171","msg":"trace[254178065] transaction","detail":"{read_only:false; response_revision:4475; number_of_response:1; }","duration":"359.578014ms","start":"2026-05-12T16:35:24.073024Z","end":"2026-05-12T16:35:24.432602Z","steps":["trace[254178065] 'process raft request' (duration: 176.476926ms)","trace[254178065] 'compare' (duration: 182.271103ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:24.433015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:24.073003Z","time spent":"359.904354ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:24.433143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.607308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-12T16:35:24.433224Z","caller":"traceutil/trace.go:171","msg":"trace[978744899] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4476; }","duration":"307.691301ms","start":"2026-05-12T16:35:24.12551Z","end":"2026-05-12T16:35:24.433201Z","steps":["trace[978744899] 'agreement among raft nodes before linearized reading' (duration: 307.443503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:24.433249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.951062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T16:35:24.433294Z","caller":"traceutil/trace.go:171","msg":"trace[1718012246] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4476; }","duration":"277.093137ms","start":"2026-05-12T16:35:24.156187Z","end":"2026-05-12T16:35:24.433281Z","steps":["trace[1718012246] 'agreement among raft nodes before linearized reading' (duration: 276.956983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:24.433343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:24.125483Z","time spent":"307.787444ms","remote":"127.0.0.1:48496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-12T16:35:24.433618Z","caller":"traceutil/trace.go:171","msg":"trace[1802360898] transaction","detail":"{read_only:false; response_revision:4476; number_of_response:1; }","duration":"349.627111ms","start":"2026-05-12T16:35:24.083972Z","end":"2026-05-12T16:35:24.433599Z","steps":["trace[1802360898] 'process raft request' (duration: 348.535898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:24.433768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:24.08395Z","time spent":"349.726864ms","remote":"127.0.0.1:48518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:24.434085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.293027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-12T16:35:24.43413Z","caller":"traceutil/trace.go:171","msg":"trace[2056794704] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4476; }","duration":"268.427672ms","start":"2026-05-12T16:35:24.165691Z","end":"2026-05-12T16:35:24.434118Z","steps":["trace[2056794704] 'agreement among raft nodes before linearized reading' (duration: 268.301018ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:29.809068Z","caller":"traceutil/trace.go:171","msg":"trace[1358320607] linearizableReadLoop","detail":"{readStateIndex:4798; appliedIndex:4797; }","duration":"120.735168ms","start":"2026-05-12T16:35:29.688299Z","end":"2026-05-12T16:35:29.809035Z","steps":["trace[1358320607] 'read index received' (duration: 50.813668ms)","trace[1358320607] 'applied index is now lower than readState.Index' (duration: 69.920139ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:29.809329Z","caller":"traceutil/trace.go:171","msg":"trace[1768871448] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"121.633935ms","start":"2026-05-12T16:35:29.68757Z","end":"2026-05-12T16:35:29.809204Z","steps":["trace[1768871448] 'process raft request' (duration: 51.610722ms)","trace[1768871448] 'compare' (duration: 69.678792ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:29.809426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.089479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-12T16:35:29.809465Z","caller":"traceutil/trace.go:171","msg":"trace[1305877540] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4601; }","duration":"121.192722ms","start":"2026-05-12T16:35:29.688262Z","end":"2026-05-12T16:35:29.809455Z","steps":["trace[1305877540] 'agreement among raft nodes before linearized reading' (duration: 120.967735ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:30.124734Z","caller":"traceutil/trace.go:171","msg":"trace[1924039537] linearizableReadLoop","detail":"{readStateIndex:4800; appliedIndex:4798; }","duration":"263.947103ms","start":"2026-05-12T16:35:29.860735Z","end":"2026-05-12T16:35:30.124682Z","steps":["trace[1924039537] 'read index received' (duration: 182.86706ms)","trace[1924039537] 'applied index is now lower than readState.Index' (duration: 81.078723ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:30.125141Z","caller":"traceutil/trace.go:171","msg":"trace[868853897] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"310.962788ms","start":"2026-05-12T16:35:29.814142Z","end":"2026-05-12T16:35:30.125105Z","steps":["trace[868853897] 'process raft request' (duration: 229.485694ms)","trace[868853897] 'compare' (duration: 80.500656ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:30.125194Z","caller":"traceutil/trace.go:171","msg":"trace[1296583306] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"307.479784ms","start":"2026-05-12T16:35:29.817688Z","end":"2026-05-12T16:35:30.125167Z","steps":["trace[1296583306] 'process raft request' (duration: 306.695741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:30.125314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:29.814129Z","time spent":"311.096762ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:30.125399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.612133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6b212bec-a310-4e8f-b289-1524eb65fdd4\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-12T16:35:30.125521Z","caller":"traceutil/trace.go:171","msg":"trace[1680381447] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6b212bec-a310-4e8f-b289-1524eb65fdd4; range_end:; response_count:1; response_revision:4603; }","duration":"264.830469ms","start":"2026-05-12T16:35:29.860671Z","end":"2026-05-12T16:35:30.125502Z","steps":["trace[1680381447] 'agreement among raft nodes before linearized reading' (duration: 264.314434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:30.125644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:29.817658Z","time spent":"307.67538ms","remote":"127.0.0.1:48434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:41.443872Z","caller":"traceutil/trace.go:171","msg":"trace[1356598093] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"122.923868ms","start":"2026-05-12T16:35:41.320913Z","end":"2026-05-12T16:35:41.443837Z","steps":["trace[1356598093] 'process raft request' (duration: 122.555637ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:41.444261Z","caller":"traceutil/trace.go:171","msg":"trace[487735935] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"123.190656ms","start":"2026-05-12T16:35:41.321048Z","end":"2026-05-12T16:35:41.444238Z","steps":["trace[487735935] 'process raft request' (duration: 122.709483ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:41.443899Z","caller":"traceutil/trace.go:171","msg":"trace[1646923346] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"122.867997ms","start":"2026-05-12T16:35:41.320999Z","end":"2026-05-12T16:35:41.443867Z","steps":["trace[1646923346] 'process raft request' (duration: 122.707002ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:38:55.476929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3254} {"level":"info","ts":"2026-05-12T16:38:55.579833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3254,"took":"101.251816ms","hash":3117726296,"current-db-size-bytes":59629568,"current-db-size":"60 MB","current-db-size-in-use-bytes":32452608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T16:38:55.579928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3117726296,"revision":3254,"compact-revision":-1} {"level":"warn","ts":"2026-05-12T16:39:09.872681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.117327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-12T16:39:09.872875Z","caller":"traceutil/trace.go:171","msg":"trace[646670148] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6579; }","duration":"278.368231ms","start":"2026-05-12T16:39:09.594481Z","end":"2026-05-12T16:39:09.872849Z","steps":["trace[646670148] 'range keys from in-memory index tree' (duration: 277.943864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:39:09.872974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.77018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:39:09.873032Z","caller":"traceutil/trace.go:171","msg":"trace[405049768] linearizableReadLoop","detail":"{readStateIndex:6875; appliedIndex:6874; }","duration":"218.22378ms","start":"2026-05-12T16:39:09.654801Z","end":"2026-05-12T16:39:09.873024Z","steps":["trace[405049768] 'read index received' (duration: 59.275127ms)","trace[405049768] 'applied index is now lower than readState.Index' (duration: 158.947873ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:39:09.873304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.487304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:39:09.873316Z","caller":"traceutil/trace.go:171","msg":"trace[1465534667] transaction","detail":"{read_only:false; response_revision:6580; number_of_response:1; }","duration":"228.216817ms","start":"2026-05-12T16:39:09.645052Z","end":"2026-05-12T16:39:09.873269Z","steps":["trace[1465534667] 'process raft request' (duration: 69.088751ms)","trace[1465534667] 'compare' (duration: 158.576327ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:39:09.873456Z","caller":"traceutil/trace.go:171","msg":"trace[492901678] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:6580; }","duration":"218.639297ms","start":"2026-05-12T16:39:09.654752Z","end":"2026-05-12T16:39:09.873391Z","steps":["trace[492901678] 'agreement among raft nodes before linearized reading' (duration: 218.478434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:39:09.873338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.534753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-12T16:39:09.87364Z","caller":"traceutil/trace.go:171","msg":"trace[2023364803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6580; }","duration":"200.886339ms","start":"2026-05-12T16:39:09.672745Z","end":"2026-05-12T16:39:09.873631Z","steps":["trace[2023364803] 'agreement among raft nodes before linearized reading' (duration: 200.526313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:39:33.72487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.332287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12351"} {"level":"info","ts":"2026-05-12T16:39:33.724968Z","caller":"traceutil/trace.go:171","msg":"trace[1475277276] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6791; }","duration":"117.53048ms","start":"2026-05-12T16:39:33.607419Z","end":"2026-05-12T16:39:33.72495Z","steps":["trace[1475277276] 'range keys from in-memory index tree' (duration: 117.170193ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:39:33.725031Z","caller":"traceutil/trace.go:171","msg":"trace[767202536] transaction","detail":"{read_only:false; response_revision:6792; number_of_response:1; }","duration":"116.36809ms","start":"2026-05-12T16:39:33.608645Z","end":"2026-05-12T16:39:33.725013Z","steps":["trace[767202536] 'process raft request' (duration: 78.306819ms)","trace[767202536] 'compare' (duration: 37.724845ms)"],"step_count":2}