{"level":"warn","ts":"2026-06-23T18:38:10.212092Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T18:38:10.212201Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.35: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.35:2380","--initial-cluster=instance=https://199.19.213.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.35: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-06-23T18:38:10.212289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T18:38:10.21231Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.35:2380"]} {"level":"info","ts":"2026-06-23T18:38:10.212337Z","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-06-23T18:38:10.213281Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"]} {"level":"info","ts":"2026-06-23T18:38:10.213396Z","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.35:2380"],"listen-peer-urls":["https://199.19.213.35:2380"],"advertise-client-urls":["https://199.19.213.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.35: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-06-23T18:38:10.22068Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.521278ms"} {"level":"info","ts":"2026-06-23T18:38:10.230224Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1606b245347dc08","cluster-id":"e68e205b85faf98a"} {"level":"info","ts":"2026-06-23T18:38:10.230328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=()"} {"level":"info","ts":"2026-06-23T18:38:10.230368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became follower at term 0"} {"level":"info","ts":"2026-06-23T18:38:10.230391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1606b245347dc08 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-23T18:38:10.230415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became follower at term 1"} {"level":"info","ts":"2026-06-23T18:38:10.230511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=(15087176555451374600)"} {"level":"warn","ts":"2026-06-23T18:38:10.239033Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-23T18:38:10.241718Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-23T18:38:10.242556Z","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-06-23T18:38:10.245543Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1606b245347dc08","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-23T18:38:10.24565Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1606b245347dc08","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-23T18:38:10.246174Z","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-06-23T18:38:10.24622Z","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-06-23T18:38:10.246232Z","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-06-23T18:38:10.247485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=(15087176555451374600)"} {"level":"info","ts":"2026-06-23T18:38:10.247612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e68e205b85faf98a","local-member-id":"d1606b245347dc08","added-peer-id":"d1606b245347dc08","added-peer-peer-urls":["https://199.19.213.35:2380"]} {"level":"info","ts":"2026-06-23T18:38:10.249871Z","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-06-23T18:38:10.250048Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1606b245347dc08","initial-advertise-peer-urls":["https://199.19.213.35:2380"],"listen-peer-urls":["https://199.19.213.35:2380"],"advertise-client-urls":["https://199.19.213.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-23T18:38:10.250077Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-23T18:38:10.250137Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.35:2380"} {"level":"info","ts":"2026-06-23T18:38:10.250157Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.35:2380"} {"level":"info","ts":"2026-06-23T18:38:11.231122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 is starting a new election at term 1"} {"level":"info","ts":"2026-06-23T18:38:11.231211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-23T18:38:11.231269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 received MsgPreVoteResp from d1606b245347dc08 at term 1"} {"level":"info","ts":"2026-06-23T18:38:11.231293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became candidate at term 2"} {"level":"info","ts":"2026-06-23T18:38:11.231304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 received MsgVoteResp from d1606b245347dc08 at term 2"} {"level":"info","ts":"2026-06-23T18:38:11.23132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became leader at term 2"} {"level":"info","ts":"2026-06-23T18:38:11.231346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1606b245347dc08 elected leader d1606b245347dc08 at term 2"} {"level":"info","ts":"2026-06-23T18:38:11.232539Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:38:11.233515Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T18:38:11.233526Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1606b245347dc08","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.35:2379]}","request-path":"/0/members/d1606b245347dc08/attributes","cluster-id":"e68e205b85faf98a","publish-timeout":"7s"} {"level":"info","ts":"2026-06-23T18:38:11.233615Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T18:38:11.233971Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-23T18:38:11.234005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-23T18:38:11.234034Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e68e205b85faf98a","local-member-id":"d1606b245347dc08","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:38:11.234164Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:38:11.234205Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:38:11.236777Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.35:2379"} {"level":"info","ts":"2026-06-23T18:38:11.237443Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-23T18:38:55.302642Z","caller":"traceutil/trace.go:171","msg":"trace[619274257] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"200.55496ms","start":"2026-06-23T18:38:55.10206Z","end":"2026-06-23T18:38:55.302615Z","steps":["trace[619274257] 'read index received' (duration: 200.334387ms)","trace[619274257] 'applied index is now lower than readState.Index' (duration: 219.673µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:38:55.302766Z","caller":"traceutil/trace.go:171","msg":"trace[1588557700] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"333.654314ms","start":"2026-06-23T18:38:54.969079Z","end":"2026-06-23T18:38:55.302733Z","steps":["trace[1588557700] 'process raft request' (duration: 333.374631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:55.302987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.877893ms","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-06-23T18:38:55.303127Z","caller":"traceutil/trace.go:171","msg":"trace[357583044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"201.088074ms","start":"2026-06-23T18:38:55.102012Z","end":"2026-06-23T18:38:55.3031Z","steps":["trace[357583044] 'agreement among raft nodes before linearized reading' (duration: 200.724291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:38:55.303437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:38:54.969047Z","time spent":"333.792625ms","remote":"127.0.0.1:35094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:39:39.338225Z","caller":"traceutil/trace.go:171","msg":"trace[349388883] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"140.414104ms","start":"2026-06-23T18:39:39.197775Z","end":"2026-06-23T18:39:39.338189Z","steps":["trace[349388883] 'process raft request' (duration: 140.181788ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:28.046742Z","caller":"traceutil/trace.go:171","msg":"trace[929293242] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"265.079772ms","start":"2026-06-23T18:40:27.781586Z","end":"2026-06-23T18:40:28.046666Z","steps":["trace[929293242] 'process raft request' (duration: 247.404228ms)","trace[929293242] 'compare' (duration: 17.558672ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:28.558054Z","caller":"traceutil/trace.go:171","msg":"trace[1746617182] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"127.270041ms","start":"2026-06-23T18:40:28.430754Z","end":"2026-06-23T18:40:28.558024Z","steps":["trace[1746617182] 'process raft request' (duration: 127.115058ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:43.107975Z","caller":"traceutil/trace.go:171","msg":"trace[43136817] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"239.589173ms","start":"2026-06-23T18:40:42.868362Z","end":"2026-06-23T18:40:43.107951Z","steps":["trace[43136817] 'read index received' (duration: 239.42231ms)","trace[43136817] 'applied index is now lower than readState.Index' (duration: 165.523µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:43.108022Z","caller":"traceutil/trace.go:171","msg":"trace[266281243] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"287.268067ms","start":"2026-06-23T18:40:42.820722Z","end":"2026-06-23T18:40:43.10799Z","steps":["trace[266281243] 'process raft request' (duration: 287.065924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:43.108252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.871007ms","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-06-23T18:40:43.108313Z","caller":"traceutil/trace.go:171","msg":"trace[152511675] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1978; }","duration":"239.983999ms","start":"2026-06-23T18:40:42.868314Z","end":"2026-06-23T18:40:43.108298Z","steps":["trace[152511675] 'agreement among raft nodes before linearized reading' (duration: 239.777916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:43.108351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.600316ms","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-06-23T18:40:43.108427Z","caller":"traceutil/trace.go:171","msg":"trace[1837900353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1978; }","duration":"106.732497ms","start":"2026-06-23T18:40:43.001676Z","end":"2026-06-23T18:40:43.108408Z","steps":["trace[1837900353] 'agreement among raft nodes before linearized reading' (duration: 106.487583ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:46.381312Z","caller":"traceutil/trace.go:171","msg":"trace[494851108] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2081; }","duration":"101.737184ms","start":"2026-06-23T18:40:46.279534Z","end":"2026-06-23T18:40:46.381272Z","steps":["trace[494851108] 'read index received' (duration: 98.40332ms)","trace[494851108] 'applied index is now lower than readState.Index' (duration: 3.332854ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:40:46.381477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.924227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:40:46.381511Z","caller":"traceutil/trace.go:171","msg":"trace[1244194240] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2008; }","duration":"102.015427ms","start":"2026-06-23T18:40:46.279485Z","end":"2026-06-23T18:40:46.3815Z","steps":["trace[1244194240] 'agreement among raft nodes before linearized reading' (duration: 101.900946ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:46.381526Z","caller":"traceutil/trace.go:171","msg":"trace[484651467] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"235.446342ms","start":"2026-06-23T18:40:46.146062Z","end":"2026-06-23T18:40:46.381509Z","steps":["trace[484651467] 'process raft request' (duration: 231.847793ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:04.35476Z","caller":"traceutil/trace.go:171","msg":"trace[57962411] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"101.40348ms","start":"2026-06-23T18:41:04.253327Z","end":"2026-06-23T18:41:04.354731Z","steps":["trace[57962411] 'process raft request' (duration: 44.277184ms)","trace[57962411] 'compare' (duration: 56.633409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:41:04.884148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.728209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:41:04.884241Z","caller":"traceutil/trace.go:171","msg":"trace[1887007480] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"107.507192ms","start":"2026-06-23T18:41:04.776716Z","end":"2026-06-23T18:41:04.884223Z","steps":["trace[1887007480] 'read index received' (duration: 30.141µs)","trace[1887007480] 'applied index is now lower than readState.Index' (duration: 107.476041ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:41:04.884576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.852007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:41:04.88474Z","caller":"traceutil/trace.go:171","msg":"trace[1744214859] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"173.088024ms","start":"2026-06-23T18:41:04.711492Z","end":"2026-06-23T18:41:04.88458Z","steps":["trace[1744214859] 'process raft request' (duration: 21.726146ms)","trace[1744214859] 'compare' (duration: 150.617037ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:41:04.884632Z","caller":"traceutil/trace.go:171","msg":"trace[1713307630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2112; }","duration":"107.945608ms","start":"2026-06-23T18:41:04.776674Z","end":"2026-06-23T18:41:04.88462Z","steps":["trace[1713307630] 'agreement among raft nodes before linearized reading' (duration: 107.859576ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:05.354082Z","caller":"traceutil/trace.go:171","msg":"trace[2109439580] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"124.403627ms","start":"2026-06-23T18:41:05.229654Z","end":"2026-06-23T18:41:05.354058Z","steps":["trace[2109439580] 'process raft request' (duration: 76.582703ms)","trace[2109439580] 'compare' (duration: 47.715922ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:41:05.513244Z","caller":"traceutil/trace.go:171","msg":"trace[1959737037] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2191; }","duration":"232.836746ms","start":"2026-06-23T18:41:05.280387Z","end":"2026-06-23T18:41:05.513224Z","steps":["trace[1959737037] 'read index received' (duration: 25.902967ms)","trace[1959737037] 'applied index is now lower than readState.Index' (duration: 206.932789ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:41:05.513341Z","caller":"traceutil/trace.go:171","msg":"trace[2139235430] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"283.383521ms","start":"2026-06-23T18:41:05.229949Z","end":"2026-06-23T18:41:05.513333Z","steps":["trace[2139235430] 'process raft request' (duration: 262.177765ms)","trace[2139235430] 'compare' (duration: 20.984403ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:41:05.513548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.142321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-06-23T18:41:05.513555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.536663ms","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-06-23T18:41:05.513628Z","caller":"traceutil/trace.go:171","msg":"trace[1800138349] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2115; }","duration":"151.653794ms","start":"2026-06-23T18:41:05.361957Z","end":"2026-06-23T18:41:05.513611Z","steps":["trace[1800138349] 'agreement among raft nodes before linearized reading' (duration: 151.440291ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:05.513657Z","caller":"traceutil/trace.go:171","msg":"trace[189682108] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2115; }","duration":"233.265533ms","start":"2026-06-23T18:41:05.280333Z","end":"2026-06-23T18:41:05.513599Z","steps":["trace[189682108] 'agreement among raft nodes before linearized reading' (duration: 233.06981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:41:05.513607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.783504ms","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-06-23T18:41:05.514211Z","caller":"traceutil/trace.go:171","msg":"trace[817592044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2115; }","duration":"121.412384ms","start":"2026-06-23T18:41:05.392783Z","end":"2026-06-23T18:41:05.514196Z","steps":["trace[817592044] 'agreement among raft nodes before linearized reading' (duration: 120.708122ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:05.725669Z","caller":"traceutil/trace.go:171","msg":"trace[1636151255] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"207.591589ms","start":"2026-06-23T18:41:05.518058Z","end":"2026-06-23T18:41:05.72565Z","steps":["trace[1636151255] 'process raft request' (duration: 124.226394ms)","trace[1636151255] 'compare' (duration: 83.149822ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:41:05.725699Z","caller":"traceutil/trace.go:171","msg":"trace[968780901] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"206.329321ms","start":"2026-06-23T18:41:05.519334Z","end":"2026-06-23T18:41:05.725663Z","steps":["trace[968780901] 'process raft request' (duration: 206.2679ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:06.946662Z","caller":"traceutil/trace.go:171","msg":"trace[1882748466] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"118.079238ms","start":"2026-06-23T18:42:06.828558Z","end":"2026-06-23T18:42:06.946637Z","steps":["trace[1882748466] 'process raft request' (duration: 117.466471ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:40.292509Z","caller":"traceutil/trace.go:171","msg":"trace[1719380449] linearizableReadLoop","detail":"{readStateIndex:2832; appliedIndex:2831; }","duration":"262.785523ms","start":"2026-06-23T18:42:40.029697Z","end":"2026-06-23T18:42:40.292482Z","steps":["trace[1719380449] 'read index received' (duration: 262.629301ms)","trace[1719380449] 'applied index is now lower than readState.Index' (duration: 155.072µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:42:40.292558Z","caller":"traceutil/trace.go:171","msg":"trace[1684456986] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"300.831218ms","start":"2026-06-23T18:42:39.991682Z","end":"2026-06-23T18:42:40.292513Z","steps":["trace[1684456986] 'process raft request' (duration: 300.651466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:40.292728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.013695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18bbc9f5c8d3b47b\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-23T18:42:40.29277Z","caller":"traceutil/trace.go:171","msg":"trace[1205316340] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18bbc9f5c8d3b47b; range_end:; response_count:1; response_revision:2732; }","duration":"263.098416ms","start":"2026-06-23T18:42:40.029662Z","end":"2026-06-23T18:42:40.29276Z","steps":["trace[1205316340] 'agreement among raft nodes before linearized reading' (duration: 262.941924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:40.293336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:42:39.991663Z","time spent":"300.98433ms","remote":"127.0.0.1:35180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:42:40.306237Z","caller":"traceutil/trace.go:171","msg":"trace[1353855140] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"245.3524ms","start":"2026-06-23T18:42:40.060865Z","end":"2026-06-23T18:42:40.306217Z","steps":["trace[1353855140] 'process raft request' (duration: 245.215468ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:40.306511Z","caller":"traceutil/trace.go:171","msg":"trace[1910584974] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"196.921968ms","start":"2026-06-23T18:42:40.109573Z","end":"2026-06-23T18:42:40.306495Z","steps":["trace[1910584974] 'process raft request' (duration: 196.606974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:40.306627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.57128ms","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-06-23T18:42:40.306664Z","caller":"traceutil/trace.go:171","msg":"trace[891985010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2734; }","duration":"114.653892ms","start":"2026-06-23T18:42:40.192Z","end":"2026-06-23T18:42:40.306654Z","steps":["trace[891985010] 'agreement among raft nodes before linearized reading' (duration: 114.572451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:42:40.307686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.308494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-23T18:42:40.307814Z","caller":"traceutil/trace.go:171","msg":"trace[75029362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2734; }","duration":"135.480416ms","start":"2026-06-23T18:42:40.17231Z","end":"2026-06-23T18:42:40.307791Z","steps":["trace[75029362] 'agreement among raft nodes before linearized reading' (duration: 134.14713ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:43:01.415306Z","caller":"traceutil/trace.go:171","msg":"trace[1405750524] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"133.143219ms","start":"2026-06-23T18:43:01.28213Z","end":"2026-06-23T18:43:01.415273Z","steps":["trace[1405750524] 'process raft request' (duration: 116.471399ms)","trace[1405750524] 'compare' (duration: 16.448597ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:43:04.314893Z","caller":"traceutil/trace.go:171","msg":"trace[1884570345] linearizableReadLoop","detail":"{readStateIndex:2964; appliedIndex:2963; }","duration":"100.443123ms","start":"2026-06-23T18:43:04.214417Z","end":"2026-06-23T18:43:04.31486Z","steps":["trace[1884570345] 'read index received' (duration: 87.513748ms)","trace[1884570345] 'applied index is now lower than readState.Index' (duration: 12.928255ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:43:04.314912Z","caller":"traceutil/trace.go:171","msg":"trace[49848262] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"100.651136ms","start":"2026-06-23T18:43:04.214233Z","end":"2026-06-23T18:43:04.314884Z","steps":["trace[49848262] 'process raft request' (duration: 87.735341ms)","trace[49848262] 'compare' (duration: 12.768353ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:43:04.315184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.732607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9281"} {"level":"info","ts":"2026-06-23T18:43:04.315255Z","caller":"traceutil/trace.go:171","msg":"trace[427528690] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2859; }","duration":"100.851518ms","start":"2026-06-23T18:43:04.214386Z","end":"2026-06-23T18:43:04.315237Z","steps":["trace[427528690] 'agreement among raft nodes before linearized reading' (duration: 100.584335ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:43:04.783567Z","caller":"traceutil/trace.go:171","msg":"trace[1891344736] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"170.923947ms","start":"2026-06-23T18:43:04.612614Z","end":"2026-06-23T18:43:04.783538Z","steps":["trace[1891344736] 'process raft request' (duration: 122.829641ms)","trace[1891344736] 'compare' (duration: 47.910394ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:43:06.005895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.139338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:43:06.005978Z","caller":"traceutil/trace.go:171","msg":"trace[1973486531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2868; }","duration":"176.287041ms","start":"2026-06-23T18:43:05.829672Z","end":"2026-06-23T18:43:06.005959Z","steps":["trace[1973486531] 'range keys from in-memory index tree' (duration: 176.061637ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:43:11.119742Z","caller":"traceutil/trace.go:171","msg":"trace[1482375424] transaction","detail":"{read_only:false; response_revision:2896; number_of_response:1; }","duration":"126.505498ms","start":"2026-06-23T18:43:10.993194Z","end":"2026-06-23T18:43:11.1197Z","steps":["trace[1482375424] 'process raft request' (duration: 109.05701ms)","trace[1482375424] 'compare' (duration: 17.304507ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:33.2691Z","caller":"traceutil/trace.go:171","msg":"trace[1528972893] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"110.417708ms","start":"2026-06-23T18:46:33.158659Z","end":"2026-06-23T18:46:33.269077Z","steps":["trace[1528972893] 'process raft request' (duration: 97.857287ms)","trace[1528972893] 'compare' (duration: 12.344437ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:35.91123Z","caller":"traceutil/trace.go:171","msg":"trace[1591457643] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"131.207919ms","start":"2026-06-23T18:46:35.779996Z","end":"2026-06-23T18:46:35.911204Z","steps":["trace[1591457643] 'process raft request' (duration: 116.663915ms)","trace[1591457643] 'compare' (duration: 13.909633ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:40.691501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.166436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-23T18:46:40.691595Z","caller":"traceutil/trace.go:171","msg":"trace[144416333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4755; }","duration":"111.307148ms","start":"2026-06-23T18:46:40.580268Z","end":"2026-06-23T18:46:40.691576Z","steps":["trace[144416333] 'range keys from in-memory index tree' (duration: 110.963531ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:52.68071Z","caller":"traceutil/trace.go:171","msg":"trace[1247571598] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"129.950025ms","start":"2026-06-23T18:46:52.550729Z","end":"2026-06-23T18:46:52.680679Z","steps":["trace[1247571598] 'process raft request' (duration: 129.752951ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:48:11.272455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2897} {"level":"info","ts":"2026-06-23T18:48:11.371967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2897,"took":"98.413112ms","hash":1622669749,"current-db-size-bytes":54243328,"current-db-size":"54 MB","current-db-size-in-use-bytes":29245440,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-23T18:48:11.372237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1622669749,"revision":2897,"compact-revision":-1} {"level":"info","ts":"2026-06-23T18:53:11.278035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5570} {"level":"info","ts":"2026-06-23T18:53:11.372471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5570,"took":"92.677311ms","hash":2663655696,"current-db-size-bytes":54243328,"current-db-size":"54 MB","current-db-size-in-use-bytes":18321408,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-23T18:53:11.372555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2663655696,"revision":5570,"compact-revision":2897} {"level":"info","ts":"2026-06-23T18:58:11.28445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7421} {"level":"info","ts":"2026-06-23T18:58:11.344414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7421,"took":"58.209407ms","hash":3367342439,"current-db-size-bytes":54243328,"current-db-size":"54 MB","current-db-size-in-use-bytes":14901248,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-06-23T18:58:11.344487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3367342439,"revision":7421,"compact-revision":5570}