{"level":"warn","ts":"2026-04-07T15:20:51.908453Z","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:20:51.908545Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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:20:51.90861Z","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:20:51.908624Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-07T15:20:51.908646Z","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:20:51.910388Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-07T15:20:51.910478Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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:20:51.914426Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.740155ms"} {"level":"info","ts":"2026-04-07T15:20:51.920014Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-07T15:20:51.920113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:20:51.920136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-07T15:20:51.920151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:20:51.92016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-07T15:20:51.920202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-07T15:20:51.929269Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:20:51.930667Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:20:51.931659Z","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:20:51.933829Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:20:51.934271Z","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:20:51.934438Z","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:20:51.934466Z","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:20:51.934408Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:20:51.936182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-07T15:20:51.936364Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-07T15:20:51.938944Z","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:20:51.939088Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-07T15:20:51.939144Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-07T15:20:51.939335Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:20:51.939397Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:20:52.521161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:20:52.52121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:20:52.521235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-07T15:20:52.521247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:20:52.521252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-07T15:20:52.52126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-07T15:20:52.521267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-07T15:20:52.524464Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:20:52.524481Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:20:52.524734Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:20:52.524579Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:20:52.525568Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:20:52.525623Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:20:52.525805Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:20:52.525924Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:20:52.525979Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:20:52.528421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-04-07T15:20:52.530862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-07T15:20:55.349616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.386467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:20:55.349812Z","caller":"traceutil/trace.go:171","msg":"trace[639855827] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"283.213692ms","start":"2026-04-07T15:20:55.066559Z","end":"2026-04-07T15:20:55.349772Z","steps":["trace[639855827] 'process raft request' (duration: 47.900729ms)","trace[639855827] 'compare' (duration: 233.267887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:20:55.65527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.569714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:20:55.655691Z","caller":"traceutil/trace.go:171","msg":"trace[1119058966] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"299.611237ms","start":"2026-04-07T15:20:55.356021Z","end":"2026-04-07T15:20:55.655632Z","steps":["trace[1119058966] 'process raft request' (duration: 163.574885ms)","trace[1119058966] 'compare' (duration: 135.391024ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:28.130234Z","caller":"traceutil/trace.go:171","msg":"trace[1861997908] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"112.940646ms","start":"2026-04-07T15:21:28.017266Z","end":"2026-04-07T15:21:28.130207Z","steps":["trace[1861997908] 'process raft request' (duration: 112.359261ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:28.481089Z","caller":"traceutil/trace.go:171","msg":"trace[747097102] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"328.187265ms","start":"2026-04-07T15:21:28.152877Z","end":"2026-04-07T15:21:28.481065Z","steps":["trace[747097102] 'process raft request' (duration: 328.052711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:28.481501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:28.152853Z","time spent":"328.310598ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:28.619703Z","caller":"traceutil/trace.go:171","msg":"trace[1917418076] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"419.677096ms","start":"2026-04-07T15:21:28.199993Z","end":"2026-04-07T15:21:28.61967Z","steps":["trace[1917418076] 'read index received' (duration: 280.981341ms)","trace[1917418076] 'applied index is now lower than readState.Index' (duration: 138.694655ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:28.619749Z","caller":"traceutil/trace.go:171","msg":"trace[517704378] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"236.92058ms","start":"2026-04-07T15:21:28.382802Z","end":"2026-04-07T15:21:28.619723Z","steps":["trace[517704378] 'process raft request' (duration: 236.775017ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:28.619854Z","caller":"traceutil/trace.go:171","msg":"trace[146643524] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"426.3905ms","start":"2026-04-07T15:21:28.193438Z","end":"2026-04-07T15:21:28.619828Z","steps":["trace[146643524] 'process raft request' (duration: 406.848984ms)","trace[146643524] 'compare' (duration: 19.075653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:28.61993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.918242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T15:21:28.619967Z","caller":"traceutil/trace.go:171","msg":"trace[173562842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"420.028454ms","start":"2026-04-07T15:21:28.199928Z","end":"2026-04-07T15:21:28.619956Z","steps":["trace[173562842] 'agreement among raft nodes before linearized reading' (duration: 419.87048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:28.619981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:28.193392Z","time spent":"426.534275ms","remote":"127.0.0.1:56448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:21:28.620003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:28.199915Z","time spent":"420.079756ms","remote":"127.0.0.1:56202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T15:21:28.679639Z","caller":"traceutil/trace.go:171","msg":"trace[353443175] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"180.503777ms","start":"2026-04-07T15:21:28.499109Z","end":"2026-04-07T15:21:28.679613Z","steps":["trace[353443175] 'process raft request' (duration: 180.378294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:28.679677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.664203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:28.679736Z","caller":"traceutil/trace.go:171","msg":"trace[2125266434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"297.730135ms","start":"2026-04-07T15:21:28.381991Z","end":"2026-04-07T15:21:28.679721Z","steps":["trace[2125266434] 'agreement among raft nodes before linearized reading' (duration: 297.628782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:28.679958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.710281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-07T15:21:28.679995Z","caller":"traceutil/trace.go:171","msg":"trace[406418863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"457.789143ms","start":"2026-04-07T15:21:28.222198Z","end":"2026-04-07T15:21:28.679987Z","steps":["trace[406418863] 'agreement among raft nodes before linearized reading' (duration: 457.6856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:28.680021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:28.222187Z","time spent":"457.826474ms","remote":"127.0.0.1:56202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T15:21:29.01345Z","caller":"traceutil/trace.go:171","msg":"trace[1035706719] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"321.086675ms","start":"2026-04-07T15:21:28.692329Z","end":"2026-04-07T15:21:29.013416Z","steps":["trace[1035706719] 'process raft request' (duration: 233.47346ms)","trace[1035706719] 'compare' (duration: 87.40817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:29.013599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:28.692308Z","time spent":"321.218239ms","remote":"127.0.0.1:56202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:22:35.5943Z","caller":"traceutil/trace.go:171","msg":"trace[1457283267] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"116.544484ms","start":"2026-04-07T15:22:35.477736Z","end":"2026-04-07T15:22:35.594281Z","steps":["trace[1457283267] 'process raft request' (duration: 116.421198ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:23:01.383634Z","caller":"traceutil/trace.go:171","msg":"trace[1002277300] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"121.876652ms","start":"2026-04-07T15:23:01.261733Z","end":"2026-04-07T15:23:01.38361Z","steps":["trace[1002277300] 'read index received' (duration: 121.658618ms)","trace[1002277300] 'applied index is now lower than readState.Index' (duration: 216.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:23:01.384034Z","caller":"traceutil/trace.go:171","msg":"trace[1549016540] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"197.327603ms","start":"2026-04-07T15:23:01.186683Z","end":"2026-04-07T15:23:01.384011Z","steps":["trace[1549016540] 'process raft request' (duration: 196.76279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:23:01.384403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.649386ms","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-07T15:23:01.384572Z","caller":"traceutil/trace.go:171","msg":"trace[511352603] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"122.834737ms","start":"2026-04-07T15:23:01.261682Z","end":"2026-04-07T15:23:01.384516Z","steps":["trace[511352603] 'agreement among raft nodes before linearized reading' (duration: 122.518619ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:23:08.384863Z","caller":"traceutil/trace.go:171","msg":"trace[1884228300] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"100.947978ms","start":"2026-04-07T15:23:08.283735Z","end":"2026-04-07T15:23:08.384683Z","steps":["trace[1884228300] 'process raft request' (duration: 100.663801ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:23:34.749644Z","caller":"traceutil/trace.go:171","msg":"trace[520755017] linearizableReadLoop","detail":"{readStateIndex:2162; appliedIndex:2161; }","duration":"162.306182ms","start":"2026-04-07T15:23:34.587293Z","end":"2026-04-07T15:23:34.7496Z","steps":["trace[520755017] 'read index received' (duration: 162.165154ms)","trace[520755017] 'applied index is now lower than readState.Index' (duration: 140.198µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:23:34.749741Z","caller":"traceutil/trace.go:171","msg":"trace[377178518] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"263.520346ms","start":"2026-04-07T15:23:34.486213Z","end":"2026-04-07T15:23:34.749733Z","steps":["trace[377178518] 'process raft request' (duration: 263.235168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:23:34.749982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.683346ms","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:23:34.750009Z","caller":"traceutil/trace.go:171","msg":"trace[622331997] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2082; }","duration":"162.75681ms","start":"2026-04-07T15:23:34.587244Z","end":"2026-04-07T15:23:34.750001Z","steps":["trace[622331997] 'agreement among raft nodes before linearized reading' (duration: 162.578019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:23:34.750121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.880157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-04-07T15:23:34.750204Z","caller":"traceutil/trace.go:171","msg":"trace[1934080907] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2082; }","duration":"140.996945ms","start":"2026-04-07T15:23:34.609197Z","end":"2026-04-07T15:23:34.750194Z","steps":["trace[1934080907] 'agreement among raft nodes before linearized reading' (duration: 140.860486ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:23:35.842051Z","caller":"traceutil/trace.go:171","msg":"trace[1347716474] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"272.64909ms","start":"2026-04-07T15:23:35.569379Z","end":"2026-04-07T15:23:35.842028Z","steps":["trace[1347716474] 'read index received' (duration: 272.480289ms)","trace[1347716474] 'applied index is now lower than readState.Index' (duration: 167.871µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:23:35.842114Z","caller":"traceutil/trace.go:171","msg":"trace[1046638459] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"293.960659ms","start":"2026-04-07T15:23:35.548118Z","end":"2026-04-07T15:23:35.842078Z","steps":["trace[1046638459] 'process raft request' (duration: 293.737545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:23:35.842389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.98078ms","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-04-07T15:23:35.842499Z","caller":"traceutil/trace.go:171","msg":"trace[924181264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2087; }","duration":"273.116918ms","start":"2026-04-07T15:23:35.569339Z","end":"2026-04-07T15:23:35.842455Z","steps":["trace[924181264] 'agreement among raft nodes before linearized reading' (duration: 272.796388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:23:35.858562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.35918ms","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-07T15:23:35.858639Z","caller":"traceutil/trace.go:171","msg":"trace[765062030] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2088; }","duration":"101.478987ms","start":"2026-04-07T15:23:35.75714Z","end":"2026-04-07T15:23:35.858619Z","steps":["trace[765062030] 'agreement among raft nodes before linearized reading' (duration: 101.279695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:23:36.200903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.538774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:23:36.201308Z","caller":"traceutil/trace.go:171","msg":"trace[137312370] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"338.59919ms","start":"2026-04-07T15:23:35.862675Z","end":"2026-04-07T15:23:36.201274Z","steps":["trace[137312370] 'process raft request' (duration: 144.610439ms)","trace[137312370] 'compare' (duration: 193.15357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:23:36.201489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:23:35.862644Z","time spent":"338.744559ms","remote":"127.0.0.1:56202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:25:13.189401Z","caller":"traceutil/trace.go:171","msg":"trace[919071851] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"100.291384ms","start":"2026-04-07T15:25:13.089058Z","end":"2026-04-07T15:25:13.18935Z","steps":["trace[919071851] 'process raft request' (duration: 100.114349ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:25:13.474624Z","caller":"traceutil/trace.go:171","msg":"trace[955760686] linearizableReadLoop","detail":"{readStateIndex:2823; appliedIndex:2822; }","duration":"125.456376ms","start":"2026-04-07T15:25:13.349138Z","end":"2026-04-07T15:25:13.474594Z","steps":["trace[955760686] 'read index received' (duration: 125.193403ms)","trace[955760686] 'applied index is now lower than readState.Index' (duration: 261.763µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:25:13.474726Z","caller":"traceutil/trace.go:171","msg":"trace[870220058] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"160.258883ms","start":"2026-04-07T15:25:13.31442Z","end":"2026-04-07T15:25:13.474679Z","steps":["trace[870220058] 'process raft request' (duration: 159.946592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:25:13.475106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.914054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a41c7fcec331fd\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-07T15:25:13.475196Z","caller":"traceutil/trace.go:171","msg":"trace[150234672] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a41c7fcec331fd; range_end:; response_count:1; response_revision:2720; }","duration":"126.081699ms","start":"2026-04-07T15:25:13.349072Z","end":"2026-04-07T15:25:13.475154Z","steps":["trace[150234672] 'agreement among raft nodes before linearized reading' (duration: 125.804087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:25:13.476246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.413594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:25:13.476348Z","caller":"traceutil/trace.go:171","msg":"trace[1811438507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2720; }","duration":"106.812336ms","start":"2026-04-07T15:25:13.369509Z","end":"2026-04-07T15:25:13.476321Z","steps":["trace[1811438507] 'agreement among raft nodes before linearized reading' (duration: 105.433273ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:04.999471Z","caller":"traceutil/trace.go:171","msg":"trace[1158177340] linearizableReadLoop","detail":"{readStateIndex:4878; appliedIndex:4877; }","duration":"165.047192ms","start":"2026-04-07T15:29:04.834384Z","end":"2026-04-07T15:29:04.999431Z","steps":["trace[1158177340] 'read index received' (duration: 127.711855ms)","trace[1158177340] 'applied index is now lower than readState.Index' (duration: 37.333657ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:29:04.999814Z","caller":"traceutil/trace.go:171","msg":"trace[1744973769] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"170.706872ms","start":"2026-04-07T15:29:04.829053Z","end":"2026-04-07T15:29:04.99976Z","steps":["trace[1744973769] 'process raft request' (duration: 133.102554ms)","trace[1744973769] 'compare' (duration: 36.955651ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:29:04.999992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.544762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-07T15:29:05.000835Z","caller":"traceutil/trace.go:171","msg":"trace[1697675886] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4699; }","duration":"166.439228ms","start":"2026-04-07T15:29:04.834373Z","end":"2026-04-07T15:29:05.000812Z","steps":["trace[1697675886] 'agreement among raft nodes before linearized reading' (duration: 165.379656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:05.000088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.587842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-07T15:29:05.001051Z","caller":"traceutil/trace.go:171","msg":"trace[1225947928] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4699; }","duration":"162.613824ms","start":"2026-04-07T15:29:04.838426Z","end":"2026-04-07T15:29:05.00104Z","steps":["trace[1225947928] 'agreement among raft nodes before linearized reading' (duration: 161.367563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:05.000434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.46678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:29:05.001245Z","caller":"traceutil/trace.go:171","msg":"trace[654874592] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4699; }","duration":"118.325294ms","start":"2026-04-07T15:29:04.882907Z","end":"2026-04-07T15:29:05.001232Z","steps":["trace[654874592] 'agreement among raft nodes before linearized reading' (duration: 117.430478ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:05.200474Z","caller":"traceutil/trace.go:171","msg":"trace[1730456986] linearizableReadLoop","detail":"{readStateIndex:4882; appliedIndex:4881; }","duration":"102.847687ms","start":"2026-04-07T15:29:05.097594Z","end":"2026-04-07T15:29:05.200442Z","steps":["trace[1730456986] 'read index received' (duration: 102.612767ms)","trace[1730456986] 'applied index is now lower than readState.Index' (duration: 233.73µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:29:05.200523Z","caller":"traceutil/trace.go:171","msg":"trace[1107689439] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"149.60076ms","start":"2026-04-07T15:29:05.050893Z","end":"2026-04-07T15:29:05.200494Z","steps":["trace[1107689439] 'process raft request' (duration: 149.36848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:05.201396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.380891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T15:29:05.201464Z","caller":"traceutil/trace.go:171","msg":"trace[160780173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4703; }","duration":"123.149843ms","start":"2026-04-07T15:29:05.078295Z","end":"2026-04-07T15:29:05.201445Z","steps":["trace[160780173] 'agreement among raft nodes before linearized reading' (duration: 122.304808ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:05.611254Z","caller":"traceutil/trace.go:171","msg":"trace[1644715139] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"262.543378ms","start":"2026-04-07T15:29:05.348686Z","end":"2026-04-07T15:29:05.611229Z","steps":["trace[1644715139] 'process raft request' (duration: 247.622751ms)","trace[1644715139] 'compare' (duration: 14.423547ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:29:05.611546Z","caller":"traceutil/trace.go:171","msg":"trace[472677170] linearizableReadLoop","detail":"{readStateIndex:4899; appliedIndex:4895; }","duration":"247.062298ms","start":"2026-04-07T15:29:05.364458Z","end":"2026-04-07T15:29:05.61152Z","steps":["trace[472677170] 'read index received' (duration: 231.87542ms)","trace[472677170] 'applied index is now lower than readState.Index' (duration: 15.183548ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:29:05.611597Z","caller":"traceutil/trace.go:171","msg":"trace[194896550] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"262.659633ms","start":"2026-04-07T15:29:05.348902Z","end":"2026-04-07T15:29:05.611562Z","steps":["trace[194896550] 'process raft request' (duration: 262.252597ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:05.611663Z","caller":"traceutil/trace.go:171","msg":"trace[1923607400] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"260.964014ms","start":"2026-04-07T15:29:05.350686Z","end":"2026-04-07T15:29:05.61165Z","steps":["trace[1923607400] 'process raft request' (duration: 260.784467ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:05.611723Z","caller":"traceutil/trace.go:171","msg":"trace[2063219108] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"262.57714ms","start":"2026-04-07T15:29:05.349128Z","end":"2026-04-07T15:29:05.611705Z","steps":["trace[2063219108] 'process raft request' (duration: 262.270828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:05.61318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.760876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-07T15:29:05.613277Z","caller":"traceutil/trace.go:171","msg":"trace[8717574] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4720; }","duration":"288.87571ms","start":"2026-04-07T15:29:05.324381Z","end":"2026-04-07T15:29:05.613257Z","steps":["trace[8717574] 'agreement among raft nodes before linearized reading' (duration: 287.304885ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:05.630254Z","caller":"traceutil/trace.go:171","msg":"trace[997146819] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"148.485285ms","start":"2026-04-07T15:29:05.481755Z","end":"2026-04-07T15:29:05.63024Z","steps":["trace[997146819] 'process raft request' (duration: 148.296217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:05.630389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.464853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:29:05.630486Z","caller":"traceutil/trace.go:171","msg":"trace[1676824610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4721; }","duration":"259.617559ms","start":"2026-04-07T15:29:05.370849Z","end":"2026-04-07T15:29:05.630467Z","steps":["trace[1676824610] 'agreement among raft nodes before linearized reading' (duration: 259.40121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:05.630512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.166259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7d5b68ef-0238-40f9-8fe0-3398f902fdf5\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-07T15:29:05.630616Z","caller":"traceutil/trace.go:171","msg":"trace[13176146] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7d5b68ef-0238-40f9-8fe0-3398f902fdf5; range_end:; response_count:1; response_revision:4721; }","duration":"258.319766ms","start":"2026-04-07T15:29:05.372286Z","end":"2026-04-07T15:29:05.630606Z","steps":["trace[13176146] 'agreement among raft nodes before linearized reading' (duration: 258.070996ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:05.78596Z","caller":"traceutil/trace.go:171","msg":"trace[387080402] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"131.926091ms","start":"2026-04-07T15:29:05.654015Z","end":"2026-04-07T15:29:05.785941Z","steps":["trace[387080402] 'process raft request' (duration: 131.880459ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:05.786155Z","caller":"traceutil/trace.go:171","msg":"trace[387539787] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4900; }","duration":"133.308896ms","start":"2026-04-07T15:29:05.652813Z","end":"2026-04-07T15:29:05.786122Z","steps":["trace[387539787] 'read index received' (duration: 58.879117ms)","trace[387539787] 'applied index is now lower than readState.Index' (duration: 74.424139ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:29:05.786364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.533485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T15:29:05.786415Z","caller":"traceutil/trace.go:171","msg":"trace[659837835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4723; }","duration":"133.6178ms","start":"2026-04-07T15:29:05.652787Z","end":"2026-04-07T15:29:05.786405Z","steps":["trace[659837835] 'agreement among raft nodes before linearized reading' (duration: 133.492674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:05.78657Z","caller":"traceutil/trace.go:171","msg":"trace[104977453] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"133.89206ms","start":"2026-04-07T15:29:05.652656Z","end":"2026-04-07T15:29:05.786548Z","steps":["trace[104977453] 'process raft request' (duration: 59.026133ms)","trace[104977453] 'compare' (duration: 74.103716ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:29:17.51735Z","caller":"traceutil/trace.go:171","msg":"trace[129042993] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5060; }","duration":"163.740729ms","start":"2026-04-07T15:29:17.353592Z","end":"2026-04-07T15:29:17.517333Z","steps":["trace[129042993] 'read index received' (duration: 163.565482ms)","trace[129042993] 'applied index is now lower than readState.Index' (duration: 174.237µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:29:17.517398Z","caller":"traceutil/trace.go:171","msg":"trace[123922741] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"164.282853ms","start":"2026-04-07T15:29:17.353084Z","end":"2026-04-07T15:29:17.517367Z","steps":["trace[123922741] 'process raft request' (duration: 164.126536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:17.51759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.958748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T15:29:17.517637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.96841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:29:17.517681Z","caller":"traceutil/trace.go:171","msg":"trace[331642578] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4876; }","duration":"164.094464ms","start":"2026-04-07T15:29:17.353566Z","end":"2026-04-07T15:29:17.517661Z","steps":["trace[331642578] 'agreement among raft nodes before linearized reading' (duration: 163.893376ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:17.517703Z","caller":"traceutil/trace.go:171","msg":"trace[465231703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4876; }","duration":"154.062503ms","start":"2026-04-07T15:29:17.363631Z","end":"2026-04-07T15:29:17.517694Z","steps":["trace[465231703] 'agreement among raft nodes before linearized reading' (duration: 153.867325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:17.517947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.131083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:29:17.517984Z","caller":"traceutil/trace.go:171","msg":"trace[1103538175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4876; }","duration":"152.181354ms","start":"2026-04-07T15:29:17.365798Z","end":"2026-04-07T15:29:17.51798Z","steps":["trace[1103538175] 'agreement among raft nodes before linearized reading' (duration: 152.132032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:17.517955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.011608ms","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-04-07T15:29:17.519402Z","caller":"traceutil/trace.go:171","msg":"trace[1295709236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4876; }","duration":"153.487591ms","start":"2026-04-07T15:29:17.365891Z","end":"2026-04-07T15:29:17.519379Z","steps":["trace[1295709236] 'agreement among raft nodes before linearized reading' (duration: 151.740547ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:17.630672Z","caller":"traceutil/trace.go:171","msg":"trace[1318922040] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"105.53606ms","start":"2026-04-07T15:29:17.525114Z","end":"2026-04-07T15:29:17.63065Z","steps":["trace[1318922040] 'process raft request' (duration: 105.376214ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:17.630724Z","caller":"traceutil/trace.go:171","msg":"trace[2619979] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"105.02768ms","start":"2026-04-07T15:29:17.525678Z","end":"2026-04-07T15:29:17.630706Z","steps":["trace[2619979] 'process raft request' (duration: 104.927425ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:17.630861Z","caller":"traceutil/trace.go:171","msg":"trace[329546499] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"105.059922ms","start":"2026-04-07T15:29:17.525791Z","end":"2026-04-07T15:29:17.63085Z","steps":["trace[329546499] 'process raft request' (duration: 104.845442ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:17.630933Z","caller":"traceutil/trace.go:171","msg":"trace[1500036456] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"105.699538ms","start":"2026-04-07T15:29:17.52522Z","end":"2026-04-07T15:29:17.630919Z","steps":["trace[1500036456] 'process raft request' (duration: 105.358733ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:17.63099Z","caller":"traceutil/trace.go:171","msg":"trace[430312005] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"105.049701ms","start":"2026-04-07T15:29:17.525933Z","end":"2026-04-07T15:29:17.630983Z","steps":["trace[430312005] 'process raft request' (duration: 104.734957ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:30:52.55768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2928} {"level":"info","ts":"2026-04-07T15:30:52.648476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2928,"took":"89.114214ms","hash":593834228,"current-db-size-bytes":54079488,"current-db-size":"54 MB","current-db-size-in-use-bytes":29368320,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T15:30:52.648575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":593834228,"revision":2928,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:35:52.563725Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5647} {"level":"info","ts":"2026-04-07T15:35:52.646174Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5647,"took":"81.267796ms","hash":2154560450,"current-db-size-bytes":54079488,"current-db-size":"54 MB","current-db-size-in-use-bytes":18132992,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T15:35:52.64622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2154560450,"revision":5647,"compact-revision":2928}