{"level":"warn","ts":"2026-04-07T15:18:03.190189Z","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-07T15:18:03.190295Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T15:18:03.19037Z","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-07T15:18:03.190379Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-07T15:18:03.190402Z","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-07T15:18:03.191134Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-07T15:18:03.191225Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T15:18:03.195909Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.332038ms"} {"level":"info","ts":"2026-04-07T15:18:03.202879Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-07T15:18:03.202961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:18:03.202988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-07T15:18:03.203007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:18:03.203016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-07T15:18:03.203062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-07T15:18:03.208542Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:18:03.210979Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:18:03.213196Z","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-07T15:18:03.215229Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:18:03.215815Z","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-07T15:18:03.215917Z","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-07T15:18:03.215933Z","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-07T15:18:03.215845Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:18:03.217993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-07T15:18:03.218185Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-07T15:18:03.21966Z","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-07T15:18:03.219848Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-07T15:18:03.219902Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-07T15:18:03.220829Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:18:03.220893Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:18:04.0038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:18:04.003875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:18:04.003922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-07T15:18:04.003942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:18:04.003953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-07T15:18:04.003978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-07T15:18:04.003993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-07T15:18:04.005194Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:18:04.005284Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:18:04.005271Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:18:04.005399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:18:04.005623Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:18:04.005743Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:18:04.006298Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:18:04.006421Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:18:04.006462Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:18:04.008575Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:18:04.01014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-07T15:18:24.147023Z","caller":"traceutil/trace.go:171","msg":"trace[1099268053] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"143.310967ms","start":"2026-04-07T15:18:24.003676Z","end":"2026-04-07T15:18:24.146987Z","steps":["trace[1099268053] 'process raft request' (duration: 143.072852ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:24.274928Z","caller":"traceutil/trace.go:171","msg":"trace[346193157] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"123.304188ms","start":"2026-04-07T15:18:24.15159Z","end":"2026-04-07T15:18:24.274894Z","steps":["trace[346193157] 'process raft request' (duration: 121.776696ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:34.427142Z","caller":"traceutil/trace.go:171","msg":"trace[1300309005] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"102.573233ms","start":"2026-04-07T15:18:34.324531Z","end":"2026-04-07T15:18:34.427104Z","steps":["trace[1300309005] 'process raft request' (duration: 91.928245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:40.511681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.785155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:18:40.51174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.371226ms","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-07T15:18:40.511831Z","caller":"traceutil/trace.go:171","msg":"trace[1560094816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"128.998969ms","start":"2026-04-07T15:18:40.382812Z","end":"2026-04-07T15:18:40.511811Z","steps":["trace[1560094816] 'range keys from in-memory index tree' (duration: 128.687523ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:40.511845Z","caller":"traceutil/trace.go:171","msg":"trace[83846821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"155.55424ms","start":"2026-04-07T15:18:40.356268Z","end":"2026-04-07T15:18:40.511823Z","steps":["trace[83846821] 'range keys from in-memory index tree' (duration: 155.139092ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:40.780016Z","caller":"traceutil/trace.go:171","msg":"trace[1606825011] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"156.685971ms","start":"2026-04-07T15:18:40.623299Z","end":"2026-04-07T15:18:40.779985Z","steps":["trace[1606825011] 'read index received' (duration: 141.58765ms)","trace[1606825011] 'applied index is now lower than readState.Index' (duration: 15.097381ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:40.780101Z","caller":"traceutil/trace.go:171","msg":"trace[1658744418] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"262.004226ms","start":"2026-04-07T15:18:40.518025Z","end":"2026-04-07T15:18:40.780029Z","steps":["trace[1658744418] 'process raft request' (duration: 246.817943ms)","trace[1658744418] 'compare' (duration: 15.03927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:18:40.780359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.042479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T15:18:40.78042Z","caller":"traceutil/trace.go:171","msg":"trace[1932680224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"157.14297ms","start":"2026-04-07T15:18:40.623263Z","end":"2026-04-07T15:18:40.780406Z","steps":["trace[1932680224] 'agreement among raft nodes before linearized reading' (duration: 156.819254ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:40.801585Z","caller":"traceutil/trace.go:171","msg":"trace[396059090] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"164.116933ms","start":"2026-04-07T15:18:40.637447Z","end":"2026-04-07T15:18:40.801564Z","steps":["trace[396059090] 'process raft request' (duration: 163.694065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:41.269486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.193325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:18:41.269623Z","caller":"traceutil/trace.go:171","msg":"trace[1310505501] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"412.16894ms","start":"2026-04-07T15:18:40.857427Z","end":"2026-04-07T15:18:41.269596Z","steps":["trace[1310505501] 'process raft request' (duration: 273.18447ms)","trace[1310505501] 'compare' (duration: 137.995682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:18:41.2697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:40.857407Z","time spent":"412.260422ms","remote":"127.0.0.1:45530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:19:32.577736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.80658ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T15:19:32.577876Z","caller":"traceutil/trace.go:171","msg":"trace[1261075656] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"218.400317ms","start":"2026-04-07T15:19:32.359452Z","end":"2026-04-07T15:19:32.577852Z","steps":["trace[1261075656] 'read index received' (duration: 41.031µs)","trace[1261075656] 'applied index is now lower than readState.Index' (duration: 218.357626ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:19:32.578021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.557999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:19:32.578057Z","caller":"traceutil/trace.go:171","msg":"trace[1081914492] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1194; }","duration":"218.641731ms","start":"2026-04-07T15:19:32.359406Z","end":"2026-04-07T15:19:32.578048Z","steps":["trace[1081914492] 'agreement among raft nodes before linearized reading' (duration: 218.55349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:19:32.578098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.230456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:19:32.578174Z","caller":"traceutil/trace.go:171","msg":"trace[1494595153] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1194; }","duration":"215.369319ms","start":"2026-04-07T15:19:32.36279Z","end":"2026-04-07T15:19:32.578159Z","steps":["trace[1494595153] 'agreement among raft nodes before linearized reading' (duration: 215.197845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:18.424136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.465073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T15:20:18.424245Z","caller":"traceutil/trace.go:171","msg":"trace[1696849438] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1853; }","duration":"125.692697ms","start":"2026-04-07T15:20:18.298525Z","end":"2026-04-07T15:20:18.424218Z","steps":["trace[1696849438] 'range keys from in-memory index tree' (duration: 125.269778ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:18.895954Z","caller":"traceutil/trace.go:171","msg":"trace[1649057837] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"216.817043ms","start":"2026-04-07T15:20:18.679098Z","end":"2026-04-07T15:20:18.895915Z","steps":["trace[1649057837] 'process raft request' (duration: 142.517762ms)","trace[1649057837] 'compare' (duration: 73.672438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:09.769994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.670652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T15:21:09.770176Z","caller":"traceutil/trace.go:171","msg":"trace[39633657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2187; }","duration":"150.906257ms","start":"2026-04-07T15:21:09.61925Z","end":"2026-04-07T15:21:09.770156Z","steps":["trace[39633657] 'range keys from in-memory index tree' (duration: 150.490479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:09.770286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.66672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-07T15:21:09.770172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.901567ms","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-07T15:21:09.770395Z","caller":"traceutil/trace.go:171","msg":"trace[1124245911] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"156.830984ms","start":"2026-04-07T15:21:09.613537Z","end":"2026-04-07T15:21:09.770368Z","steps":["trace[1124245911] 'range keys from in-memory index tree' (duration: 156.363583ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:09.770445Z","caller":"traceutil/trace.go:171","msg":"trace[491324670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2187; }","duration":"151.162863ms","start":"2026-04-07T15:21:09.61924Z","end":"2026-04-07T15:21:09.770403Z","steps":["trace[491324670] 'range keys from in-memory index tree' (duration: 150.792965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:22:38.206121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.028893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:22:38.206323Z","caller":"traceutil/trace.go:171","msg":"trace[2058906502] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"379.160646ms","start":"2026-04-07T15:22:37.82713Z","end":"2026-04-07T15:22:38.206291Z","steps":["trace[2058906502] 'process raft request' (duration: 110.594491ms)","trace[2058906502] 'compare' (duration: 267.769868ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:22:38.206521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:22:37.827116Z","time spent":"379.300398ms","remote":"127.0.0.1:45558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:26:33.740607Z","caller":"traceutil/trace.go:171","msg":"trace[1427990353] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"101.659182ms","start":"2026-04-07T15:26:33.638913Z","end":"2026-04-07T15:26:33.740573Z","steps":["trace[1427990353] 'process raft request' (duration: 101.204914ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:38.79214Z","caller":"traceutil/trace.go:171","msg":"trace[1571254642] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"102.789433ms","start":"2026-04-07T15:26:38.689293Z","end":"2026-04-07T15:26:38.792083Z","steps":["trace[1571254642] 'process raft request' (duration: 102.61342ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:39.109564Z","caller":"traceutil/trace.go:171","msg":"trace[1142032218] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"293.775447ms","start":"2026-04-07T15:26:38.81576Z","end":"2026-04-07T15:26:39.109535Z","steps":["trace[1142032218] 'process raft request' (duration: 293.590033ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:39.113119Z","caller":"traceutil/trace.go:171","msg":"trace[2122509499] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"194.308067ms","start":"2026-04-07T15:26:38.918794Z","end":"2026-04-07T15:26:39.113102Z","steps":["trace[2122509499] 'process raft request' (duration: 194.121482ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:39.113353Z","caller":"traceutil/trace.go:171","msg":"trace[1034959050] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"100.027542ms","start":"2026-04-07T15:26:39.013304Z","end":"2026-04-07T15:26:39.113332Z","steps":["trace[1034959050] 'process raft request' (duration: 99.717596ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:26:58.028107Z","caller":"traceutil/trace.go:171","msg":"trace[152210161] linearizableReadLoop","detail":"{readStateIndex:5625; appliedIndex:5624; }","duration":"116.549409ms","start":"2026-04-07T15:26:57.91153Z","end":"2026-04-07T15:26:58.02808Z","steps":["trace[152210161] 'read index received' (duration: 116.231644ms)","trace[152210161] 'applied index is now lower than readState.Index' (duration: 315.995µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:26:58.028199Z","caller":"traceutil/trace.go:171","msg":"trace[676609892] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"138.924066ms","start":"2026-04-07T15:26:57.889245Z","end":"2026-04-07T15:26:58.028169Z","steps":["trace[676609892] 'process raft request' (duration: 138.645461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:26:58.028352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.805684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-78d22\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T15:26:58.028405Z","caller":"traceutil/trace.go:171","msg":"trace[1219870159] range","detail":"{range_begin:/registry/pods/openstack/keepalived-78d22; range_end:; response_count:1; response_revision:5426; }","duration":"116.913436ms","start":"2026-04-07T15:26:57.911478Z","end":"2026-04-07T15:26:58.028391Z","steps":["trace[1219870159] 'agreement among raft nodes before linearized reading' (duration: 116.725343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:00.094846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.304683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:27:00.09513Z","caller":"traceutil/trace.go:171","msg":"trace[68106007] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"144.779183ms","start":"2026-04-07T15:26:59.950322Z","end":"2026-04-07T15:27:00.095102Z","steps":["trace[68106007] 'process raft request' (duration: 27.133134ms)","trace[68106007] 'compare' (duration: 117.037178ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:00.09571Z","caller":"traceutil/trace.go:171","msg":"trace[1843984290] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"145.277734ms","start":"2026-04-07T15:26:59.950393Z","end":"2026-04-07T15:27:00.095671Z","steps":["trace[1843984290] 'process raft request' (duration: 144.548349ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:00.095765Z","caller":"traceutil/trace.go:171","msg":"trace[1791770063] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"145.225593ms","start":"2026-04-07T15:26:59.950508Z","end":"2026-04-07T15:27:00.095734Z","steps":["trace[1791770063] 'process raft request' (duration: 145.06713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:00.096109Z","caller":"traceutil/trace.go:171","msg":"trace[1721815716] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"145.002448ms","start":"2026-04-07T15:26:59.951091Z","end":"2026-04-07T15:27:00.096093Z","steps":["trace[1721815716] 'process raft request' (duration: 144.53448ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:00.096459Z","caller":"traceutil/trace.go:171","msg":"trace[1367347047] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"144.004559ms","start":"2026-04-07T15:26:59.952404Z","end":"2026-04-07T15:27:00.096408Z","steps":["trace[1367347047] 'process raft request' (duration: 143.552781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:12.212994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.312929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:27:12.213204Z","caller":"traceutil/trace.go:171","msg":"trace[1043055429] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"151.290146ms","start":"2026-04-07T15:27:12.061895Z","end":"2026-04-07T15:27:12.213185Z","steps":["trace[1043055429] 'process raft request' (duration: 31.694221ms)","trace[1043055429] 'compare' (duration: 119.184367ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:12.213415Z","caller":"traceutil/trace.go:171","msg":"trace[78021652] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"149.94604ms","start":"2026-04-07T15:27:12.063402Z","end":"2026-04-07T15:27:12.213348Z","steps":["trace[78021652] 'process raft request' (duration: 149.796037ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:12.213429Z","caller":"traceutil/trace.go:171","msg":"trace[1955433070] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"151.336946ms","start":"2026-04-07T15:27:12.062072Z","end":"2026-04-07T15:27:12.213409Z","steps":["trace[1955433070] 'process raft request' (duration: 151.0339ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:12.599629Z","caller":"traceutil/trace.go:171","msg":"trace[1241531935] transaction","detail":"{read_only:false; response_revision:5632; number_of_response:1; }","duration":"139.482556ms","start":"2026-04-07T15:27:12.460097Z","end":"2026-04-07T15:27:12.59958Z","steps":["trace[1241531935] 'process raft request' (duration: 139.217151ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:04.029561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3002} {"level":"info","ts":"2026-04-07T15:28:04.119921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3002,"took":"88.233788ms","hash":4283750933,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":31387648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T15:28:04.119993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4283750933,"revision":3002,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:33:04.055905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6080} {"level":"info","ts":"2026-04-07T15:33:04.153882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6080,"took":"96.003345ms","hash":3384987938,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":21880832,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-07T15:33:04.153958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3384987938,"revision":6080,"compact-revision":3002} {"level":"info","ts":"2026-04-07T15:33:11.659898Z","caller":"traceutil/trace.go:171","msg":"trace[1671062919] linearizableReadLoop","detail":"{readStateIndex:8635; appliedIndex:8634; }","duration":"202.241188ms","start":"2026-04-07T15:33:11.457629Z","end":"2026-04-07T15:33:11.65987Z","steps":["trace[1671062919] 'read index received' (duration: 201.177505ms)","trace[1671062919] 'applied index is now lower than readState.Index' (duration: 1.062503ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:11.659965Z","caller":"traceutil/trace.go:171","msg":"trace[1433994074] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"264.111895ms","start":"2026-04-07T15:33:11.395821Z","end":"2026-04-07T15:33:11.659933Z","steps":["trace[1433994074] 'process raft request' (duration: 262.979901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:11.660264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.602185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T15:33:11.660361Z","caller":"traceutil/trace.go:171","msg":"trace[614423698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8345; }","duration":"202.774719ms","start":"2026-04-07T15:33:11.457561Z","end":"2026-04-07T15:33:11.660336Z","steps":["trace[614423698] 'agreement among raft nodes before linearized reading' (duration: 202.445822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:11.699705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.921974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-07T15:33:11.699764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.880948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T15:33:11.699793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.487043ms","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-04-07T15:33:11.699868Z","caller":"traceutil/trace.go:171","msg":"trace[1155568976] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8347; }","duration":"101.608435ms","start":"2026-04-07T15:33:11.598244Z","end":"2026-04-07T15:33:11.699853Z","steps":["trace[1155568976] 'agreement among raft nodes before linearized reading' (duration: 101.422041ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:11.699822Z","caller":"traceutil/trace.go:171","msg":"trace[11429134] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8347; }","duration":"137.99072ms","start":"2026-04-07T15:33:11.561817Z","end":"2026-04-07T15:33:11.699808Z","steps":["trace[11429134] 'agreement among raft nodes before linearized reading' (duration: 137.872288ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:11.69977Z","caller":"traceutil/trace.go:171","msg":"trace[235897518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8347; }","duration":"242.036726ms","start":"2026-04-07T15:33:11.457717Z","end":"2026-04-07T15:33:11.699754Z","steps":["trace[235897518] 'agreement among raft nodes before linearized reading' (duration: 241.863142ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:11.849056Z","caller":"traceutil/trace.go:171","msg":"trace[1738344689] transaction","detail":"{read_only:false; response_revision:8349; number_of_response:1; }","duration":"142.455294ms","start":"2026-04-07T15:33:11.706573Z","end":"2026-04-07T15:33:11.849028Z","steps":["trace[1738344689] 'process raft request' (duration: 142.289791ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:22.786816Z","caller":"traceutil/trace.go:171","msg":"trace[1760240546] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"246.055411ms","start":"2026-04-07T15:33:22.540716Z","end":"2026-04-07T15:33:22.786772Z","steps":["trace[1760240546] 'process raft request' (duration: 245.852847ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:23.836196Z","caller":"traceutil/trace.go:171","msg":"trace[1580948543] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"120.277512ms","start":"2026-04-07T15:33:23.715889Z","end":"2026-04-07T15:33:23.836166Z","steps":["trace[1580948543] 'process raft request' (duration: 120.048027ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:50.624316Z","caller":"traceutil/trace.go:171","msg":"trace[1801905889] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"433.85314ms","start":"2026-04-07T15:33:50.190442Z","end":"2026-04-07T15:33:50.624295Z","steps":["trace[1801905889] 'process raft request' (duration: 433.721867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:50.624442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:50.190417Z","time spent":"433.962652ms","remote":"127.0.0.1:45558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:50.635074Z","caller":"traceutil/trace.go:171","msg":"trace[1695209560] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8906; }","duration":"255.297518ms","start":"2026-04-07T15:33:50.379747Z","end":"2026-04-07T15:33:50.635044Z","steps":["trace[1695209560] 'read index received' (duration: 244.395446ms)","trace[1695209560] 'applied index is now lower than readState.Index' (duration: 10.900772ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:50.635732Z","caller":"traceutil/trace.go:171","msg":"trace[252488592] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"418.305569ms","start":"2026-04-07T15:33:50.217416Z","end":"2026-04-07T15:33:50.635721Z","steps":["trace[252488592] 'process raft request' (duration: 417.090514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:50.635865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:50.217386Z","time spent":"418.383842ms","remote":"127.0.0.1:45558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:33:50.6361Z","caller":"traceutil/trace.go:171","msg":"trace[1069540917] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"415.840918ms","start":"2026-04-07T15:33:50.220251Z","end":"2026-04-07T15:33:50.636092Z","steps":["trace[1069540917] 'process raft request' (duration: 414.744154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:50.636148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:33:50.220236Z","time spent":"415.886749ms","remote":"127.0.0.1:45558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:33:50.636182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.435542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:33:50.636266Z","caller":"traceutil/trace.go:171","msg":"trace[888150672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8608; }","duration":"256.572345ms","start":"2026-04-07T15:33:50.379668Z","end":"2026-04-07T15:33:50.63624Z","steps":["trace[888150672] 'agreement among raft nodes before linearized reading' (duration: 256.447802ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:52.777495Z","caller":"traceutil/trace.go:171","msg":"trace[1178776702] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"148.596205ms","start":"2026-04-07T15:33:52.628861Z","end":"2026-04-07T15:33:52.777458Z","steps":["trace[1178776702] 'process raft request' (duration: 148.326729ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:52.854667Z","caller":"traceutil/trace.go:171","msg":"trace[418721616] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"213.210602ms","start":"2026-04-07T15:33:52.641426Z","end":"2026-04-07T15:33:52.854637Z","steps":["trace[418721616] 'process raft request' (duration: 212.947155ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:33:52.854895Z","caller":"traceutil/trace.go:171","msg":"trace[2098394761] linearizableReadLoop","detail":"{readStateIndex:8919; appliedIndex:8916; }","duration":"208.970472ms","start":"2026-04-07T15:33:52.645908Z","end":"2026-04-07T15:33:52.854879Z","steps":["trace[2098394761] 'read index received' (duration: 131.170545ms)","trace[2098394761] 'applied index is now lower than readState.Index' (duration: 77.795347ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:52.855028Z","caller":"traceutil/trace.go:171","msg":"trace[240111099] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"213.400065ms","start":"2026-04-07T15:33:52.641583Z","end":"2026-04-07T15:33:52.854983Z","steps":["trace[240111099] 'process raft request' (duration: 212.992467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.855273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.100628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-78d22\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T15:33:52.855363Z","caller":"traceutil/trace.go:171","msg":"trace[1643992690] range","detail":"{range_begin:/registry/pods/openstack/keepalived-78d22; range_end:; response_count:1; response_revision:8617; }","duration":"205.241552ms","start":"2026-04-07T15:33:52.650101Z","end":"2026-04-07T15:33:52.855343Z","steps":["trace[1643992690] 'agreement among raft nodes before linearized reading' (duration: 204.947625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:33:52.855758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.619305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T15:33:52.855826Z","caller":"traceutil/trace.go:171","msg":"trace[231204159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8617; }","duration":"209.931501ms","start":"2026-04-07T15:33:52.645878Z","end":"2026-04-07T15:33:52.855809Z","steps":["trace[231204159] 'agreement among raft nodes before linearized reading' (duration: 209.121114ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:34:02.95617Z","caller":"traceutil/trace.go:171","msg":"trace[1182184558] linearizableReadLoop","detail":"{readStateIndex:9020; appliedIndex:9019; }","duration":"172.969813ms","start":"2026-04-07T15:34:02.783171Z","end":"2026-04-07T15:34:02.95614Z","steps":["trace[1182184558] 'read index received' (duration: 172.859041ms)","trace[1182184558] 'applied index is now lower than readState.Index' (duration: 109.462µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:34:02.956367Z","caller":"traceutil/trace.go:171","msg":"trace[254103576] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8713; }","duration":"214.124961ms","start":"2026-04-07T15:34:02.742205Z","end":"2026-04-07T15:34:02.95633Z","steps":["trace[254103576] 'process raft request' (duration: 213.744082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:34:02.956486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.291872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tk9xn\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-04-07T15:34:02.956559Z","caller":"traceutil/trace.go:171","msg":"trace[1038613061] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tk9xn; range_end:; response_count:1; response_revision:8713; }","duration":"173.414263ms","start":"2026-04-07T15:34:02.783127Z","end":"2026-04-07T15:34:02.956541Z","steps":["trace[1038613061] 'agreement among raft nodes before linearized reading' (duration: 173.167378ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:34:02.965998Z","caller":"traceutil/trace.go:171","msg":"trace[864227347] transaction","detail":"{read_only:false; response_revision:8714; number_of_response:1; }","duration":"152.095279ms","start":"2026-04-07T15:34:02.813881Z","end":"2026-04-07T15:34:02.965977Z","steps":["trace[864227347] 'process raft request' (duration: 151.539147ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:34:03.579622Z","caller":"traceutil/trace.go:171","msg":"trace[1557363906] transaction","detail":"{read_only:false; response_revision:8720; number_of_response:1; }","duration":"150.804872ms","start":"2026-04-07T15:34:03.428772Z","end":"2026-04-07T15:34:03.579577Z","steps":["trace[1557363906] 'process raft request' (duration: 146.737976ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:34:03.588404Z","caller":"traceutil/trace.go:171","msg":"trace[1739038008] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"136.15834ms","start":"2026-04-07T15:34:03.452227Z","end":"2026-04-07T15:34:03.588385Z","steps":["trace[1739038008] 'process raft request' (duration: 136.039657ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:34:04.423051Z","caller":"traceutil/trace.go:171","msg":"trace[60364646] linearizableReadLoop","detail":"{readStateIndex:9034; appliedIndex:9033; }","duration":"175.379355ms","start":"2026-04-07T15:34:04.247646Z","end":"2026-04-07T15:34:04.423025Z","steps":["trace[60364646] 'read index received' (duration: 175.220402ms)","trace[60364646] 'applied index is now lower than readState.Index' (duration: 157.743µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:34:04.423102Z","caller":"traceutil/trace.go:171","msg":"trace[1611249924] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"178.472892ms","start":"2026-04-07T15:34:04.244594Z","end":"2026-04-07T15:34:04.423066Z","steps":["trace[1611249924] 'process raft request' (duration: 178.263178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:34:04.423494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.824304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111905"} {"level":"info","ts":"2026-04-07T15:34:04.423574Z","caller":"traceutil/trace.go:171","msg":"trace[133607122] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8727; }","duration":"175.926846ms","start":"2026-04-07T15:34:04.247629Z","end":"2026-04-07T15:34:04.423556Z","steps":["trace[133607122] 'agreement among raft nodes before linearized reading' (duration: 175.529988ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:34:20.546972Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002418.wal"} {"level":"info","ts":"2026-04-07T15:35:28.450335Z","caller":"traceutil/trace.go:171","msg":"trace[1330101804] transaction","detail":"{read_only:false; response_revision:9486; number_of_response:1; }","duration":"255.288414ms","start":"2026-04-07T15:35:28.195016Z","end":"2026-04-07T15:35:28.450305Z","steps":["trace[1330101804] 'process raft request' (duration: 255.045009ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:35:28.720154Z","caller":"traceutil/trace.go:171","msg":"trace[1056943255] transaction","detail":"{read_only:false; response_revision:9490; number_of_response:1; }","duration":"134.56457ms","start":"2026-04-07T15:35:28.585541Z","end":"2026-04-07T15:35:28.720105Z","steps":["trace[1056943255] 'process raft request' (duration: 77.794979ms)","trace[1056943255] 'compare' (duration: 56.549426ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:35:28.720173Z","caller":"traceutil/trace.go:171","msg":"trace[1482722914] transaction","detail":"{read_only:false; response_revision:9491; number_of_response:1; }","duration":"133.974027ms","start":"2026-04-07T15:35:28.586168Z","end":"2026-04-07T15:35:28.720142Z","steps":["trace[1482722914] 'process raft request' (duration: 133.877034ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:35:48.07317Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T15:35:48.079509Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T15:35:48.079601Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T15:38:04.063391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8171} {"level":"info","ts":"2026-04-07T15:38:04.131471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8171,"took":"64.835054ms","hash":3808467701,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":29360128,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T15:38:04.13153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3808467701,"revision":8171,"compact-revision":6080} {"level":"info","ts":"2026-04-07T15:43:04.070401Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10738} {"level":"info","ts":"2026-04-07T15:43:04.151724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10738,"took":"79.619109ms","hash":251909601,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":21291008,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-07T15:43:04.151779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":251909601,"revision":10738,"compact-revision":8171}