{"level":"warn","ts":"2026-04-20T11:58:15.608584Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T11:58:15.608667Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T11:58:15.608728Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T11:58:15.608739Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-20T11:58:15.608757Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T11:58:15.609413Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-04-20T11:58:15.609486Z","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.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T11:58:15.624546Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.824597ms"} {"level":"info","ts":"2026-04-20T11:58:15.635396Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-04-20T11:58:15.635604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T11:58:15.635659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-04-20T11:58:15.635682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T11:58:15.635696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-04-20T11:58:15.635761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-04-20T11:58:15.644527Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T11:58:15.645955Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T11:58:15.646999Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T11:58:15.649314Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T11:58:15.649551Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T11:58:15.649601Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T11:58:15.649689Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T11:58:15.649707Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T11:58:15.65446Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T11:58:15.654601Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-20T11:58:15.654666Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-20T11:58:15.654756Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T11:58:15.654793Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T11:58:15.654999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-04-20T11:58:15.655173Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-20T11:58:15.736177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T11:58:15.736243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T11:58:15.736268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-04-20T11:58:15.736299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-04-20T11:58:15.736313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-20T11:58:15.736347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-04-20T11:58:15.736357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-20T11:58:15.737844Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:58:15.738519Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T11:58:15.738764Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:58:15.739006Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:58:15.739372Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T11:58:15.739399Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T11:58:15.739596Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:58:15.740079Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:58:15.740124Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:58:15.741048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T11:58:15.743375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-04-20T11:58:46.626923Z","caller":"traceutil/trace.go:171","msg":"trace[599133353] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"132.245997ms","start":"2026-04-20T11:58:46.494644Z","end":"2026-04-20T11:58:46.62689Z","steps":["trace[599133353] 'read index received' (duration: 132.109231ms)","trace[599133353] 'applied index is now lower than readState.Index' (duration: 135.406µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:58:46.627106Z","caller":"traceutil/trace.go:171","msg":"trace[633067592] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"150.372229ms","start":"2026-04-20T11:58:46.476695Z","end":"2026-04-20T11:58:46.627068Z","steps":["trace[633067592] 'process raft request' (duration: 150.011472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:46.627192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.51476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T11:58:46.627288Z","caller":"traceutil/trace.go:171","msg":"trace[1380082416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"132.656226ms","start":"2026-04-20T11:58:46.494598Z","end":"2026-04-20T11:58:46.627254Z","steps":["trace[1380082416] 'agreement among raft nodes before linearized reading' (duration: 132.427476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:47.115515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.38191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T11:58:47.11565Z","caller":"traceutil/trace.go:171","msg":"trace[1124305097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"438.587139ms","start":"2026-04-20T11:58:46.677027Z","end":"2026-04-20T11:58:47.115614Z","steps":["trace[1124305097] 'range keys from in-memory index tree' (duration: 438.050253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:47.115744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:46.676989Z","time spent":"438.743266ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T11:58:47.146549Z","caller":"traceutil/trace.go:171","msg":"trace[2082880643] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"283.795082ms","start":"2026-04-20T11:58:46.862723Z","end":"2026-04-20T11:58:47.146518Z","steps":["trace[2082880643] 'process raft request' (duration: 281.962376ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:47.146724Z","caller":"traceutil/trace.go:171","msg":"trace[469805982] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"131.450764ms","start":"2026-04-20T11:58:47.015122Z","end":"2026-04-20T11:58:47.146573Z","steps":["trace[469805982] 'read index received' (duration: 128.916715ms)","trace[469805982] 'applied index is now lower than readState.Index' (duration: 2.530889ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:58:47.147238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.125026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:58:47.147343Z","caller":"traceutil/trace.go:171","msg":"trace[1059703237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"132.301054ms","start":"2026-04-20T11:58:47.014997Z","end":"2026-04-20T11:58:47.147298Z","steps":["trace[1059703237] 'agreement among raft nodes before linearized reading' (duration: 131.678295ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:48.710145Z","caller":"traceutil/trace.go:171","msg":"trace[1992981369] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"130.624742ms","start":"2026-04-20T11:58:48.579487Z","end":"2026-04-20T11:58:48.710112Z","steps":["trace[1992981369] 'process raft request' (duration: 130.430743ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:02.210099Z","caller":"traceutil/trace.go:171","msg":"trace[1090716265] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"110.544654ms","start":"2026-04-20T11:59:02.099522Z","end":"2026-04-20T11:59:02.210067Z","steps":["trace[1090716265] 'process raft request' (duration: 110.336396ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:55.828557Z","caller":"traceutil/trace.go:171","msg":"trace[2072874103] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"130.557313ms","start":"2026-04-20T12:00:55.697965Z","end":"2026-04-20T12:00:55.828522Z","steps":["trace[2072874103] 'process raft request' (duration: 127.982932ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:55.829296Z","caller":"traceutil/trace.go:171","msg":"trace[1141910943] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"126.02454ms","start":"2026-04-20T12:00:55.703246Z","end":"2026-04-20T12:00:55.82927Z","steps":["trace[1141910943] 'process raft request' (duration: 125.198247ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:15.136099Z","caller":"traceutil/trace.go:171","msg":"trace[1299780191] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"105.219587ms","start":"2026-04-20T12:01:15.030849Z","end":"2026-04-20T12:01:15.136068Z","steps":["trace[1299780191] 'process raft request' (duration: 104.908532ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:15.447849Z","caller":"traceutil/trace.go:171","msg":"trace[1483389862] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"218.305368ms","start":"2026-04-20T12:01:15.229519Z","end":"2026-04-20T12:01:15.447824Z","steps":["trace[1483389862] 'process raft request' (duration: 218.135026ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:15.46013Z","caller":"traceutil/trace.go:171","msg":"trace[2048901078] linearizableReadLoop","detail":"{readStateIndex:1771; appliedIndex:1769; }","duration":"105.724515ms","start":"2026-04-20T12:01:15.354396Z","end":"2026-04-20T12:01:15.460121Z","steps":["trace[2048901078] 'read index received' (duration: 93.408077ms)","trace[2048901078] 'applied index is now lower than readState.Index' (duration: 12.315778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:01:15.460215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.807616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:01:15.460279Z","caller":"traceutil/trace.go:171","msg":"trace[1263954540] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1715; }","duration":"105.878748ms","start":"2026-04-20T12:01:15.354357Z","end":"2026-04-20T12:01:15.460236Z","steps":["trace[1263954540] 'agreement among raft nodes before linearized reading' (duration: 105.805567ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:15.460341Z","caller":"traceutil/trace.go:171","msg":"trace[960315511] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"156.300494ms","start":"2026-04-20T12:01:15.304018Z","end":"2026-04-20T12:01:15.460319Z","steps":["trace[960315511] 'process raft request' (duration: 156.059931ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:35.987187Z","caller":"traceutil/trace.go:171","msg":"trace[1765944154] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"106.431544ms","start":"2026-04-20T12:01:35.880728Z","end":"2026-04-20T12:01:35.98716Z","steps":["trace[1765944154] 'process raft request' (duration: 106.239471ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:14.84953Z","caller":"traceutil/trace.go:171","msg":"trace[1490991537] linearizableReadLoop","detail":"{readStateIndex:2624; appliedIndex:2623; }","duration":"125.1489ms","start":"2026-04-20T12:02:14.724352Z","end":"2026-04-20T12:02:14.849501Z","steps":["trace[1490991537] 'read index received' (duration: 125.017178ms)","trace[1490991537] 'applied index is now lower than readState.Index' (duration: 131.182µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:14.849562Z","caller":"traceutil/trace.go:171","msg":"trace[1476474763] transaction","detail":"{read_only:false; response_revision:2525; number_of_response:1; }","duration":"142.859067ms","start":"2026-04-20T12:02:14.706691Z","end":"2026-04-20T12:02:14.84955Z","steps":["trace[1476474763] 'process raft request' (duration: 142.703695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:14.849693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.331772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T12:02:14.84973Z","caller":"traceutil/trace.go:171","msg":"trace[997735544] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2525; }","duration":"125.398423ms","start":"2026-04-20T12:02:14.724318Z","end":"2026-04-20T12:02:14.849717Z","steps":["trace[997735544] 'agreement among raft nodes before linearized reading' (duration: 125.269272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:15.90308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.437888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T12:02:15.903186Z","caller":"traceutil/trace.go:171","msg":"trace[1872434838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2531; }","duration":"155.63124ms","start":"2026-04-20T12:02:15.747531Z","end":"2026-04-20T12:02:15.903162Z","steps":["trace[1872434838] 'range keys from in-memory index tree' (duration: 155.152654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:15.903438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.042019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T12:02:15.903529Z","caller":"traceutil/trace.go:171","msg":"trace[109532760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2531; }","duration":"153.16086ms","start":"2026-04-20T12:02:15.750343Z","end":"2026-04-20T12:02:15.903504Z","steps":["trace[109532760] 'range keys from in-memory index tree' (duration: 152.501292ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:16.090053Z","caller":"traceutil/trace.go:171","msg":"trace[301843757] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"182.254515ms","start":"2026-04-20T12:02:15.907764Z","end":"2026-04-20T12:02:16.090018Z","steps":["trace[301843757] 'process raft request' (duration: 182.086053ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:16.100802Z","caller":"traceutil/trace.go:171","msg":"trace[1276548321] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"192.956237ms","start":"2026-04-20T12:02:15.90782Z","end":"2026-04-20T12:02:16.100777Z","steps":["trace[1276548321] 'process raft request' (duration: 192.845026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:34.903567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.430102ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T12:06:34.903935Z","caller":"traceutil/trace.go:171","msg":"trace[195651487] linearizableReadLoop","detail":"{readStateIndex:4316; appliedIndex:4315; }","duration":"107.035281ms","start":"2026-04-20T12:06:34.796869Z","end":"2026-04-20T12:06:34.903905Z","steps":["trace[195651487] 'read index received' (duration: 45.561µs)","trace[195651487] 'applied index is now lower than readState.Index' (duration: 106.98702ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:06:34.904223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.295717ms","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-04-20T12:06:34.904274Z","caller":"traceutil/trace.go:171","msg":"trace[556873383] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4156; }","duration":"107.4442ms","start":"2026-04-20T12:06:34.796813Z","end":"2026-04-20T12:06:34.904257Z","steps":["trace[556873383] 'agreement among raft nodes before linearized reading' (duration: 107.174484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:16.337244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.800547ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:08:16.33741Z","caller":"traceutil/trace.go:171","msg":"trace[70971529] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5570; }","duration":"111.98894ms","start":"2026-04-20T12:08:16.225392Z","end":"2026-04-20T12:08:16.337381Z","steps":["trace[70971529] 'range keys from in-memory index tree' (duration: 111.763756ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:16.416337Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2871} {"level":"info","ts":"2026-04-20T12:08:16.547377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2871,"took":"129.056861ms","hash":2099936147,"current-db-size-bytes":53612544,"current-db-size":"54 MB","current-db-size-in-use-bytes":30470144,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-20T12:08:16.54782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2099936147,"revision":2871,"compact-revision":-1} {"level":"info","ts":"2026-04-20T12:08:21.174831Z","caller":"traceutil/trace.go:171","msg":"trace[1784181183] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"183.826508ms","start":"2026-04-20T12:08:20.990976Z","end":"2026-04-20T12:08:21.174803Z","steps":["trace[1784181183] 'process raft request' (duration: 183.736567ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:21.174848Z","caller":"traceutil/trace.go:171","msg":"trace[293849366] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"184.472169ms","start":"2026-04-20T12:08:20.990342Z","end":"2026-04-20T12:08:21.174814Z","steps":["trace[293849366] 'process raft request' (duration: 151.079585ms)","trace[293849366] 'compare' (duration: 33.1531ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:21.174875Z","caller":"traceutil/trace.go:171","msg":"trace[1463304265] linearizableReadLoop","detail":"{readStateIndex:5828; appliedIndex:5824; }","duration":"160.912342ms","start":"2026-04-20T12:08:21.013941Z","end":"2026-04-20T12:08:21.174853Z","steps":["trace[1463304265] 'read index received' (duration: 55.750758ms)","trace[1463304265] 'applied index is now lower than readState.Index' (duration: 105.159704ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:21.174964Z","caller":"traceutil/trace.go:171","msg":"trace[31226000] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"183.853838ms","start":"2026-04-20T12:08:20.991071Z","end":"2026-04-20T12:08:21.174925Z","steps":["trace[31226000] 'process raft request' (duration: 183.673515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:21.175007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.053905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:08:21.175046Z","caller":"traceutil/trace.go:171","msg":"trace[166529788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5621; }","duration":"161.148687ms","start":"2026-04-20T12:08:21.013888Z","end":"2026-04-20T12:08:21.175037Z","steps":["trace[166529788] 'agreement among raft nodes before linearized reading' (duration: 161.053406ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:58.543099Z","caller":"traceutil/trace.go:171","msg":"trace[386087135] transaction","detail":"{read_only:false; response_revision:5912; number_of_response:1; }","duration":"124.614247ms","start":"2026-04-20T12:08:58.418451Z","end":"2026-04-20T12:08:58.543065Z","steps":["trace[386087135] 'process raft request' (duration: 124.376393ms)"],"step_count":1}