{"level":"warn","ts":"2026-04-18T22:16:46.260697Z","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-18T22:16:46.260849Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-18T22:16:46.260951Z","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-18T22:16:46.260972Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-18T22:16:46.261003Z","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-18T22:16:46.262105Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-04-18T22:16:46.262409Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-18T22:16:46.268405Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.701361ms"} {"level":"info","ts":"2026-04-18T22:16:46.276621Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-04-18T22:16:46.276751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:16:46.276789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-04-18T22:16:46.276811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:16:46.276836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-04-18T22:16:46.276904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-04-18T22:16:46.282551Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:16:46.284318Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:16:46.285661Z","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-18T22:16:46.288678Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:16:46.288883Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:16:46.28903Z","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-18T22:16:46.289147Z","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-18T22:16:46.289163Z","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-18T22:16:46.291114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-04-18T22:16:46.291625Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-18T22:16:46.293386Z","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-18T22:16:46.293647Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:16:46.293688Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:16:46.293763Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-18T22:16:46.293786Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-18T22:16:47.077943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:16:47.078011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:16:47.078064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-04-18T22:16:47.078084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:16:47.078096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-18T22:16:47.078122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-04-18T22:16:47.078139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-18T22:16:47.079701Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:16:47.079733Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:16:47.079798Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:16:47.079812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:16:47.080107Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:16:47.080187Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:16:47.080874Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:16:47.081001Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:16:47.081046Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:16:47.084007Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T22:16:47.084192Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-04-18T22:17:02.165048Z","caller":"traceutil/trace.go:171","msg":"trace[135446030] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"194.540011ms","start":"2026-04-18T22:17:01.970396Z","end":"2026-04-18T22:17:02.164936Z","steps":["trace[135446030] 'process raft request' (duration: 194.408707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:24.307224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.810157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:17:24.307609Z","caller":"traceutil/trace.go:171","msg":"trace[1324574917] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"441.565195ms","start":"2026-04-18T22:17:23.86602Z","end":"2026-04-18T22:17:24.307585Z","steps":["trace[1324574917] 'process raft request' (duration: 247.772691ms)","trace[1324574917] 'compare' (duration: 192.446987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:24.308912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.865976Z","time spent":"442.898102ms","remote":"127.0.0.1:36060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:17:40.40772Z","caller":"traceutil/trace.go:171","msg":"trace[755560828] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"253.598112ms","start":"2026-04-18T22:17:40.154099Z","end":"2026-04-18T22:17:40.407697Z","steps":["trace[755560828] 'read index received' (duration: 253.372476ms)","trace[755560828] 'applied index is now lower than readState.Index' (duration: 224.676µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:40.407887Z","caller":"traceutil/trace.go:171","msg":"trace[1606894173] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"263.1977ms","start":"2026-04-18T22:17:40.144678Z","end":"2026-04-18T22:17:40.407876Z","steps":["trace[1606894173] 'process raft request' (duration: 262.877871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:40.408488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.331443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-18T22:17:40.408595Z","caller":"traceutil/trace.go:171","msg":"trace[1995574678] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:781; }","duration":"254.523259ms","start":"2026-04-18T22:17:40.154049Z","end":"2026-04-18T22:17:40.408572Z","steps":["trace[1995574678] 'agreement among raft nodes before linearized reading' (duration: 254.039245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:08.932719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.64628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:18:08.932831Z","caller":"traceutil/trace.go:171","msg":"trace[1412469254] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"303.627068ms","start":"2026-04-18T22:18:08.629186Z","end":"2026-04-18T22:18:08.932813Z","steps":["trace[1412469254] 'process raft request' (duration: 47.69158ms)","trace[1412469254] 'compare' (duration: 255.539357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:08.932892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:08.629156Z","time spent":"303.70671ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:56.350869Z","caller":"traceutil/trace.go:171","msg":"trace[409909871] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"487.137686ms","start":"2026-04-18T22:18:55.863694Z","end":"2026-04-18T22:18:56.350832Z","steps":["trace[409909871] 'process raft request' (duration: 486.90939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:56.351215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:55.863659Z","time spent":"487.355522ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:56.485372Z","caller":"traceutil/trace.go:171","msg":"trace[1266378936] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1916; }","duration":"468.085244ms","start":"2026-04-18T22:18:56.017257Z","end":"2026-04-18T22:18:56.485343Z","steps":["trace[1266378936] 'read index received' (duration: 333.424057ms)","trace[1266378936] 'applied index is now lower than readState.Index' (duration: 134.658926ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:56.485436Z","caller":"traceutil/trace.go:171","msg":"trace[1219994672] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"617.875513ms","start":"2026-04-18T22:18:55.867503Z","end":"2026-04-18T22:18:56.485379Z","steps":["trace[1219994672] 'process raft request' (duration: 617.589635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:56.485583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.311829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:18:56.485573Z","caller":"traceutil/trace.go:171","msg":"trace[28650495] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"474.76675ms","start":"2026-04-18T22:18:56.010765Z","end":"2026-04-18T22:18:56.485532Z","steps":["trace[28650495] 'process raft request' (duration: 474.517893ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:56.485614Z","caller":"traceutil/trace.go:171","msg":"trace[1036918746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"468.427543ms","start":"2026-04-18T22:18:56.017179Z","end":"2026-04-18T22:18:56.485607Z","steps":["trace[1036918746] 'agreement among raft nodes before linearized reading' (duration: 468.33704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:56.485643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:56.017148Z","time spent":"468.488445ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:18:56.485682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:55.867447Z","time spent":"618.092789ms","remote":"127.0.0.1:40610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10931,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:18:56.485735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:56.010727Z","time spent":"474.909944ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:18:56.485795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.883841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-18T22:18:56.485836Z","caller":"traceutil/trace.go:171","msg":"trace[1440236819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"288.975653ms","start":"2026-04-18T22:18:56.19685Z","end":"2026-04-18T22:18:56.485826Z","steps":["trace[1440236819] 'agreement among raft nodes before linearized reading' (duration: 288.774787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:56.486012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.014106ms","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":"info","ts":"2026-04-18T22:18:56.486149Z","caller":"traceutil/trace.go:171","msg":"trace[1791090865] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"382.218941ms","start":"2026-04-18T22:18:56.103894Z","end":"2026-04-18T22:18:56.486113Z","steps":["trace[1791090865] 'agreement among raft nodes before linearized reading' (duration: 381.719247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:56.486252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:56.103867Z","time spent":"382.369026ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-18T22:18:56.821756Z","caller":"traceutil/trace.go:171","msg":"trace[1578036249] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"327.764767ms","start":"2026-04-18T22:18:56.493965Z","end":"2026-04-18T22:18:56.82173Z","steps":["trace[1578036249] 'process raft request' (duration: 240.179357ms)","trace[1578036249] 'compare' (duration: 87.389324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:56.82212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:56.493952Z","time spent":"327.887791ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:56.822183Z","caller":"traceutil/trace.go:171","msg":"trace[1548145131] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"328.028725ms","start":"2026-04-18T22:18:56.494121Z","end":"2026-04-18T22:18:56.82215Z","steps":["trace[1548145131] 'process raft request' (duration: 327.551401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:56.822381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:56.494085Z","time spent":"328.188799ms","remote":"127.0.0.1:35842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:56.823066Z","caller":"traceutil/trace.go:171","msg":"trace[252506835] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"328.637502ms","start":"2026-04-18T22:18:56.494415Z","end":"2026-04-18T22:18:56.823052Z","steps":["trace[252506835] 'process raft request' (duration: 327.689675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:56.823162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:56.494401Z","time spent":"328.706833ms","remote":"127.0.0.1:35842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:56.823306Z","caller":"traceutil/trace.go:171","msg":"trace[542226252] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1919; }","duration":"257.76232ms","start":"2026-04-18T22:18:56.565526Z","end":"2026-04-18T22:18:56.823288Z","steps":["trace[542226252] 'read index received' (duration: 5.475533ms)","trace[542226252] 'applied index is now lower than readState.Index' (duration: 252.285637ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:56.823441Z","caller":"traceutil/trace.go:171","msg":"trace[388169848] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"328.995461ms","start":"2026-04-18T22:18:56.494434Z","end":"2026-04-18T22:18:56.823429Z","steps":["trace[388169848] 'process raft request' (duration: 328.556009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:56.823546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:56.494401Z","time spent":"329.102054ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:18:56.824417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.86063ms","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":"info","ts":"2026-04-18T22:18:56.82455Z","caller":"traceutil/trace.go:171","msg":"trace[1527389140] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"259.017915ms","start":"2026-04-18T22:18:56.565499Z","end":"2026-04-18T22:18:56.824517Z","steps":["trace[1527389140] 'agreement among raft nodes before linearized reading' (duration: 258.163791ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:09.306034Z","caller":"traceutil/trace.go:171","msg":"trace[1299435337] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"135.377706ms","start":"2026-04-18T22:19:09.17062Z","end":"2026-04-18T22:19:09.305998Z","steps":["trace[1299435337] 'process raft request' (duration: 135.083768ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:09.596133Z","caller":"traceutil/trace.go:171","msg":"trace[171296956] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"283.623064ms","start":"2026-04-18T22:19:09.312472Z","end":"2026-04-18T22:19:09.596095Z","steps":["trace[171296956] 'process raft request' (duration: 214.297814ms)","trace[171296956] 'compare' (duration: 68.798795ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:09.596314Z","caller":"traceutil/trace.go:171","msg":"trace[1212389052] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"283.253453ms","start":"2026-04-18T22:19:09.313019Z","end":"2026-04-18T22:19:09.596272Z","steps":["trace[1212389052] 'process raft request' (duration: 282.956084ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:46.17872Z","caller":"traceutil/trace.go:171","msg":"trace[724297697] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2246; }","duration":"352.392922ms","start":"2026-04-18T22:19:45.826301Z","end":"2026-04-18T22:19:46.178694Z","steps":["trace[724297697] 'read index received' (duration: 309.337323ms)","trace[724297697] 'applied index is now lower than readState.Index' (duration: 43.054329ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:46.178822Z","caller":"traceutil/trace.go:171","msg":"trace[807943684] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"375.721696ms","start":"2026-04-18T22:19:45.803051Z","end":"2026-04-18T22:19:46.178772Z","steps":["trace[807943684] 'process raft request' (duration: 332.718508ms)","trace[807943684] 'compare' (duration: 42.819203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:46.178966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.647379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-18T22:19:46.179004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:45.803033Z","time spent":"375.88291ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:46.179041Z","caller":"traceutil/trace.go:171","msg":"trace[392859492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2169; }","duration":"352.767152ms","start":"2026-04-18T22:19:45.826257Z","end":"2026-04-18T22:19:46.179024Z","steps":["trace[392859492] 'agreement among raft nodes before linearized reading' (duration: 352.572627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:46.179062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.393322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-18T22:19:46.179072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:45.826236Z","time spent":"352.830024ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-18T22:19:46.179102Z","caller":"traceutil/trace.go:171","msg":"trace[1671829092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2169; }","duration":"348.490054ms","start":"2026-04-18T22:19:45.830601Z","end":"2026-04-18T22:19:46.179091Z","steps":["trace[1671829092] 'agreement among raft nodes before linearized reading' (duration: 348.3367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:46.179138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:45.830579Z","time spent":"348.550336ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:19:46.179239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.620035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:46.179264Z","caller":"traceutil/trace.go:171","msg":"trace[1739289537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2169; }","duration":"257.686597ms","start":"2026-04-18T22:19:45.921571Z","end":"2026-04-18T22:19:46.179257Z","steps":["trace[1739289537] 'agreement among raft nodes before linearized reading' (duration: 257.647376ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:46.426205Z","caller":"traceutil/trace.go:171","msg":"trace[731193939] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"242.113007ms","start":"2026-04-18T22:19:46.184069Z","end":"2026-04-18T22:19:46.426182Z","steps":["trace[731193939] 'process raft request' (duration: 195.076798ms)","trace[731193939] 'compare' (duration: 46.958886ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:46.482683Z","caller":"traceutil/trace.go:171","msg":"trace[720618770] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2247; }","duration":"283.590991ms","start":"2026-04-18T22:19:46.199073Z","end":"2026-04-18T22:19:46.482664Z","steps":["trace[720618770] 'read index received' (duration: 180.071493ms)","trace[720618770] 'applied index is now lower than readState.Index' (duration: 103.514798ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:46.482778Z","caller":"traceutil/trace.go:171","msg":"trace[181921184] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"298.537714ms","start":"2026-04-18T22:19:46.184201Z","end":"2026-04-18T22:19:46.482739Z","steps":["trace[181921184] 'process raft request' (duration: 298.302608ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:46.482832Z","caller":"traceutil/trace.go:171","msg":"trace[756172250] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"298.065651ms","start":"2026-04-18T22:19:46.184729Z","end":"2026-04-18T22:19:46.482795Z","steps":["trace[756172250] 'process raft request' (duration: 297.899176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:46.482887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.809168ms","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":"info","ts":"2026-04-18T22:19:46.482915Z","caller":"traceutil/trace.go:171","msg":"trace[3164316] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"283.88726ms","start":"2026-04-18T22:19:46.199019Z","end":"2026-04-18T22:19:46.482906Z","steps":["trace[3164316] 'agreement among raft nodes before linearized reading' (duration: 283.726156ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:46.754636Z","caller":"traceutil/trace.go:171","msg":"trace[2006055935] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2250; }","duration":"168.705678ms","start":"2026-04-18T22:19:46.585912Z","end":"2026-04-18T22:19:46.754618Z","steps":["trace[2006055935] 'read index received' (duration: 140.997543ms)","trace[2006055935] 'applied index is now lower than readState.Index' (duration: 27.707135ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:46.75483Z","caller":"traceutil/trace.go:171","msg":"trace[1760322548] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"209.828734ms","start":"2026-04-18T22:19:46.544967Z","end":"2026-04-18T22:19:46.754796Z","steps":["trace[1760322548] 'process raft request' (duration: 182.023386ms)","trace[1760322548] 'compare' (duration: 27.252073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:46.755232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.279404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-18T22:19:46.75533Z","caller":"traceutil/trace.go:171","msg":"trace[1874333603] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2174; }","duration":"169.438008ms","start":"2026-04-18T22:19:46.585869Z","end":"2026-04-18T22:19:46.755307Z","steps":["trace[1874333603] 'agreement among raft nodes before linearized reading' (duration: 168.819411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:26.758071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.626029ms","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-18T22:20:26.758207Z","caller":"traceutil/trace.go:171","msg":"trace[688897422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2562; }","duration":"125.841535ms","start":"2026-04-18T22:20:26.632341Z","end":"2026-04-18T22:20:26.758182Z","steps":["trace[688897422] 'range keys from in-memory index tree' (duration: 125.386643ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:00.40032Z","caller":"traceutil/trace.go:171","msg":"trace[768454943] linearizableReadLoop","detail":"{readStateIndex:2869; appliedIndex:2868; }","duration":"112.450805ms","start":"2026-04-18T22:21:00.287828Z","end":"2026-04-18T22:21:00.400279Z","steps":["trace[768454943] 'read index received' (duration: 61.518808ms)","trace[768454943] 'applied index is now lower than readState.Index' (duration: 50.928376ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:21:00.400377Z","caller":"traceutil/trace.go:171","msg":"trace[1071261506] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"196.812115ms","start":"2026-04-18T22:21:00.203515Z","end":"2026-04-18T22:21:00.400327Z","steps":["trace[1071261506] 'process raft request' (duration: 145.90794ms)","trace[1071261506] 'compare' (duration: 50.539475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:21:00.400636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.802075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-18T22:21:00.400782Z","caller":"traceutil/trace.go:171","msg":"trace[515422187] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2767; }","duration":"113.00523ms","start":"2026-04-18T22:21:00.287758Z","end":"2026-04-18T22:21:00.400763Z","steps":["trace[515422187] 'agreement among raft nodes before linearized reading' (duration: 112.676211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:25:13.081881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.68839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ba18ab8b-acb8-4f0f-aeca-407dbd13219b\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-04-18T22:25:13.082013Z","caller":"traceutil/trace.go:171","msg":"trace[574297119] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ba18ab8b-acb8-4f0f-aeca-407dbd13219b; range_end:; response_count:1; response_revision:5055; }","duration":"152.872064ms","start":"2026-04-18T22:25:12.929113Z","end":"2026-04-18T22:25:13.081985Z","steps":["trace[574297119] 'range keys from in-memory index tree' (duration: 152.586446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:25:13.081985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.98529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-18T22:25:13.082089Z","caller":"traceutil/trace.go:171","msg":"trace[1636656639] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5055; }","duration":"184.119704ms","start":"2026-04-18T22:25:12.897951Z","end":"2026-04-18T22:25:13.082071Z","steps":["trace[1636656639] 'range keys from in-memory index tree' (duration: 183.774475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:25:13.082451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.569496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:25:13.082509Z","caller":"traceutil/trace.go:171","msg":"trace[1191704410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5055; }","duration":"157.362358ms","start":"2026-04-18T22:25:12.925134Z","end":"2026-04-18T22:25:13.082496Z","steps":["trace[1191704410] 'range keys from in-memory index tree' (duration: 156.281608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:25:15.038539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.47658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:25:15.038664Z","caller":"traceutil/trace.go:171","msg":"trace[1955741581] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"204.682709ms","start":"2026-04-18T22:25:14.833963Z","end":"2026-04-18T22:25:15.038646Z","steps":["trace[1955741581] 'process raft request' (duration: 74.993163ms)","trace[1955741581] 'compare' (duration: 129.299816ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:25:15.047069Z","caller":"traceutil/trace.go:171","msg":"trace[1334179283] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"212.640229ms","start":"2026-04-18T22:25:14.8344Z","end":"2026-04-18T22:25:15.047041Z","steps":["trace[1334179283] 'process raft request' (duration: 212.497035ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:25:15.047433Z","caller":"traceutil/trace.go:171","msg":"trace[427414057] linearizableReadLoop","detail":"{readStateIndex:5291; appliedIndex:5291; }","duration":"124.945336ms","start":"2026-04-18T22:25:14.922471Z","end":"2026-04-18T22:25:15.047417Z","steps":["trace[427414057] 'read index received' (duration: 124.938806ms)","trace[427414057] 'applied index is now lower than readState.Index' (duration: 5.04µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:25:15.047558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.0763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:25:15.047598Z","caller":"traceutil/trace.go:171","msg":"trace[782732321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5103; }","duration":"125.175493ms","start":"2026-04-18T22:25:14.922413Z","end":"2026-04-18T22:25:15.047589Z","steps":["trace[782732321] 'agreement among raft nodes before linearized reading' (duration: 125.09076ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:25:31.423624Z","caller":"traceutil/trace.go:171","msg":"trace[221885984] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"103.077224ms","start":"2026-04-18T22:25:31.320502Z","end":"2026-04-18T22:25:31.423579Z","steps":["trace[221885984] 'process raft request' (duration: 25.677066ms)","trace[221885984] 'compare' (duration: 77.187043ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:26:38.737042Z","caller":"traceutil/trace.go:171","msg":"trace[1087989783] transaction","detail":"{read_only:false; response_revision:6055; number_of_response:1; }","duration":"128.679588ms","start":"2026-04-18T22:26:38.608316Z","end":"2026-04-18T22:26:38.736996Z","steps":["trace[1087989783] 'process raft request' (duration: 128.39284ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:26:47.105191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3022} {"level":"info","ts":"2026-04-18T22:26:47.188005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3022,"took":"80.631886ms","hash":224489313,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":31625216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-18T22:26:47.188068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":224489313,"revision":3022,"compact-revision":-1} {"level":"info","ts":"2026-04-18T22:31:33.021856Z","caller":"traceutil/trace.go:171","msg":"trace[354303020] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"111.747047ms","start":"2026-04-18T22:31:32.910078Z","end":"2026-04-18T22:31:33.021825Z","steps":["trace[354303020] 'process raft request' (duration: 111.114939ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:44.5346Z","caller":"traceutil/trace.go:171","msg":"trace[1165353073] transaction","detail":"{read_only:false; response_revision:8308; number_of_response:1; }","duration":"331.480304ms","start":"2026-04-18T22:31:44.203089Z","end":"2026-04-18T22:31:44.53457Z","steps":["trace[1165353073] 'process raft request' (duration: 331.31046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:44.534763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:31:44.203068Z","time spent":"331.614048ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:31:44.544793Z","caller":"traceutil/trace.go:171","msg":"trace[1018407567] linearizableReadLoop","detail":"{readStateIndex:8596; appliedIndex:8594; }","duration":"265.891123ms","start":"2026-04-18T22:31:44.278887Z","end":"2026-04-18T22:31:44.544778Z","steps":["trace[1018407567] 'read index received' (duration: 255.492626ms)","trace[1018407567] 'applied index is now lower than readState.Index' (duration: 10.397687ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:31:44.544916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.024917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:31:44.544942Z","caller":"traceutil/trace.go:171","msg":"trace[941466609] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:8309; }","duration":"266.065948ms","start":"2026-04-18T22:31:44.278869Z","end":"2026-04-18T22:31:44.544935Z","steps":["trace[941466609] 'agreement among raft nodes before linearized reading' (duration: 265.999416ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:44.544903Z","caller":"traceutil/trace.go:171","msg":"trace[1690068010] transaction","detail":"{read_only:false; response_revision:8309; number_of_response:1; }","duration":"306.893336ms","start":"2026-04-18T22:31:44.237972Z","end":"2026-04-18T22:31:44.544865Z","steps":["trace[1690068010] 'process raft request' (duration: 306.711751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:44.545086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.137579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:31:44.545139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:31:44.237939Z","time spent":"307.106652ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:31:44.545148Z","caller":"traceutil/trace.go:171","msg":"trace[1003163536] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8309; }","duration":"111.253272ms","start":"2026-04-18T22:31:44.433879Z","end":"2026-04-18T22:31:44.545132Z","steps":["trace[1003163536] 'agreement among raft nodes before linearized reading' (duration: 111.155539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:44.545156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.893884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-18T22:31:44.545217Z","caller":"traceutil/trace.go:171","msg":"trace[463976997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8309; }","duration":"236.019348ms","start":"2026-04-18T22:31:44.309183Z","end":"2026-04-18T22:31:44.545203Z","steps":["trace[463976997] 'agreement among raft nodes before linearized reading' (duration: 235.816313ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:45.719665Z","caller":"traceutil/trace.go:171","msg":"trace[2113491766] linearizableReadLoop","detail":"{readStateIndex:8601; appliedIndex:8600; }","duration":"375.352176ms","start":"2026-04-18T22:31:45.344279Z","end":"2026-04-18T22:31:45.719631Z","steps":["trace[2113491766] 'read index received' (duration: 319.865184ms)","trace[2113491766] 'applied index is now lower than readState.Index' (duration: 55.484642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:31:45.719947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.647784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:31:45.719992Z","caller":"traceutil/trace.go:171","msg":"trace[590078807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8313; }","duration":"375.752087ms","start":"2026-04-18T22:31:45.344229Z","end":"2026-04-18T22:31:45.719981Z","steps":["trace[590078807] 'agreement among raft nodes before linearized reading' (duration: 375.564202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:45.720034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:31:45.344207Z","time spent":"375.816569ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:31:45.795816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.611053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T22:31:45.795841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.618716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:31:45.795863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.935789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:31:45.79591Z","caller":"traceutil/trace.go:171","msg":"trace[1169935893] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8314; }","duration":"438.784698ms","start":"2026-04-18T22:31:45.357102Z","end":"2026-04-18T22:31:45.795886Z","steps":["trace[1169935893] 'agreement among raft nodes before linearized reading' (duration: 438.559332ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:45.795949Z","caller":"traceutil/trace.go:171","msg":"trace[963530800] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8314; }","duration":"281.084763ms","start":"2026-04-18T22:31:45.514845Z","end":"2026-04-18T22:31:45.795929Z","steps":["trace[963530800] 'agreement among raft nodes before linearized reading' (duration: 280.94996ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:45.795936Z","caller":"traceutil/trace.go:171","msg":"trace[112588854] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8314; }","duration":"156.743889ms","start":"2026-04-18T22:31:45.639169Z","end":"2026-04-18T22:31:45.795913Z","steps":["trace[112588854] 'agreement among raft nodes before linearized reading' (duration: 156.510983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:45.795962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:31:45.357069Z","time spent":"438.881911ms","remote":"127.0.0.1:40572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":7,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:31:45.795914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.260817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:31:45.796155Z","caller":"traceutil/trace.go:171","msg":"trace[34385696] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8314; }","duration":"273.542804ms","start":"2026-04-18T22:31:45.522594Z","end":"2026-04-18T22:31:45.796137Z","steps":["trace[34385696] 'agreement among raft nodes before linearized reading' (duration: 273.224515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:45.796104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.240279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:31:45.796225Z","caller":"traceutil/trace.go:171","msg":"trace[1844428132] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8314; }","duration":"385.502846ms","start":"2026-04-18T22:31:45.410703Z","end":"2026-04-18T22:31:45.796206Z","steps":["trace[1844428132] 'agreement among raft nodes before linearized reading' (duration: 385.155757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:45.796314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:31:45.410682Z","time spent":"385.61737ms","remote":"127.0.0.1:40610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":61,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:31:45.79648Z","caller":"traceutil/trace.go:171","msg":"trace[207360485] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"277.684219ms","start":"2026-04-18T22:31:45.518086Z","end":"2026-04-18T22:31:45.79577Z","steps":["trace[207360485] 'process raft request' (duration: 277.320189ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:45.849737Z","caller":"traceutil/trace.go:171","msg":"trace[1848638514] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"124.684024ms","start":"2026-04-18T22:31:45.725026Z","end":"2026-04-18T22:31:45.84971Z","steps":["trace[1848638514] 'process raft request' (duration: 96.150786ms)","trace[1848638514] 'compare' (duration: 28.235379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:31:46.298118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.565103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:31:46.29845Z","caller":"traceutil/trace.go:171","msg":"trace[1232693346] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8317; }","duration":"123.889151ms","start":"2026-04-18T22:31:46.174441Z","end":"2026-04-18T22:31:46.29833Z","steps":["trace[1232693346] 'range keys from in-memory index tree' (duration: 123.422029ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:46.728351Z","caller":"traceutil/trace.go:171","msg":"trace[352207281] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"130.318269ms","start":"2026-04-18T22:31:46.597994Z","end":"2026-04-18T22:31:46.728312Z","steps":["trace[352207281] 'process raft request' (duration: 130.218926ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:46.728433Z","caller":"traceutil/trace.go:171","msg":"trace[161028361] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"132.783587ms","start":"2026-04-18T22:31:46.595599Z","end":"2026-04-18T22:31:46.728383Z","steps":["trace[161028361] 'process raft request' (duration: 41.149046ms)","trace[161028361] 'compare' (duration: 91.276131ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:31:47.194535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6127} {"level":"info","ts":"2026-04-18T22:31:47.341867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6127,"took":"144.349307ms","hash":757799184,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":23814144,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-18T22:31:47.341963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":757799184,"revision":6127,"compact-revision":3022} {"level":"warn","ts":"2026-04-18T22:32:15.771504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.844865ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-18T22:32:15.771673Z","caller":"traceutil/trace.go:171","msg":"trace[378762886] linearizableReadLoop","detail":"{readStateIndex:8824; appliedIndex:8823; }","duration":"337.291834ms","start":"2026-04-18T22:32:15.434353Z","end":"2026-04-18T22:32:15.771645Z","steps":["trace[378762886] 'read index received' (duration: 127.47424ms)","trace[378762886] 'applied index is now lower than readState.Index' (duration: 209.815454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:32:15.772138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.545557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T22:32:15.772218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.839099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-18T22:32:15.772254Z","caller":"traceutil/trace.go:171","msg":"trace[118608242] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8527; }","duration":"337.937443ms","start":"2026-04-18T22:32:15.434302Z","end":"2026-04-18T22:32:15.77224Z","steps":["trace[118608242] 'agreement among raft nodes before linearized reading' (duration: 337.714357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:15.772284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:32:15.434281Z","time spent":"337.995284ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-18T22:32:15.772284Z","caller":"traceutil/trace.go:171","msg":"trace[2011244374] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:8527; }","duration":"209.760933ms","start":"2026-04-18T22:32:15.562495Z","end":"2026-04-18T22:32:15.772256Z","steps":["trace[2011244374] 'agreement among raft nodes before linearized reading' (duration: 209.503996ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:16.83552Z","caller":"traceutil/trace.go:171","msg":"trace[1649546790] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"108.375083ms","start":"2026-04-18T22:32:16.727118Z","end":"2026-04-18T22:32:16.835493Z","steps":["trace[1649546790] 'process raft request' (duration: 108.138526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:28.446964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.42057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xncbz\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-04-18T22:32:28.447057Z","caller":"traceutil/trace.go:171","msg":"trace[1259524170] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xncbz; range_end:; response_count:1; response_revision:8631; }","duration":"156.546193ms","start":"2026-04-18T22:32:28.290491Z","end":"2026-04-18T22:32:28.447037Z","steps":["trace[1259524170] 'range keys from in-memory index tree' (duration: 156.331238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:28.447086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.894561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-18T22:32:28.447225Z","caller":"traceutil/trace.go:171","msg":"trace[1329792920] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8631; }","duration":"158.083466ms","start":"2026-04-18T22:32:28.289112Z","end":"2026-04-18T22:32:28.447196Z","steps":["trace[1329792920] 'range keys from in-memory index tree' (duration: 157.771378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:28.447262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.844253ms","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-18T22:32:28.447356Z","caller":"traceutil/trace.go:171","msg":"trace[2055686784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8631; }","duration":"101.982337ms","start":"2026-04-18T22:32:28.345352Z","end":"2026-04-18T22:32:28.447335Z","steps":["trace[2055686784] 'range keys from in-memory index tree' (duration: 101.659728ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:28.575059Z","caller":"traceutil/trace.go:171","msg":"trace[1834288618] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8632; }","duration":"126.415181ms","start":"2026-04-18T22:32:28.448619Z","end":"2026-04-18T22:32:28.575034Z","steps":["trace[1834288618] 'process raft request' (duration: 126.239566ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:28.607021Z","caller":"traceutil/trace.go:171","msg":"trace[747392188] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"158.159538ms","start":"2026-04-18T22:32:28.448821Z","end":"2026-04-18T22:32:28.60698Z","steps":["trace[747392188] 'process raft request' (duration: 157.975173ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:28.607196Z","caller":"traceutil/trace.go:171","msg":"trace[221293741] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"155.425823ms","start":"2026-04-18T22:32:28.45175Z","end":"2026-04-18T22:32:28.607176Z","steps":["trace[221293741] 'process raft request' (duration: 155.162006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:29.59205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.282376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:32:29.592318Z","caller":"traceutil/trace.go:171","msg":"trace[521796244] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"304.88445ms","start":"2026-04-18T22:32:29.287409Z","end":"2026-04-18T22:32:29.592294Z","steps":["trace[521796244] 'process raft request' (duration: 304.779767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:29.592636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:32:29.287385Z","time spent":"305.014784ms","remote":"127.0.0.1:40666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:32:29.592381Z","caller":"traceutil/trace.go:171","msg":"trace[2021699682] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"310.068194ms","start":"2026-04-18T22:32:29.282255Z","end":"2026-04-18T22:32:29.592323Z","steps":["trace[2021699682] 'process raft request' (duration: 174.437098ms)","trace[2021699682] 'compare' (duration: 135.120902ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:32:29.593114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:32:29.282198Z","time spent":"310.821094ms","remote":"127.0.0.1:40524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":583,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:32:29.813792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.113817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:32:29.813902Z","caller":"traceutil/trace.go:171","msg":"trace[1681612499] linearizableReadLoop","detail":"{readStateIndex:8947; appliedIndex:8946; }","duration":"218.811343ms","start":"2026-04-18T22:32:29.595073Z","end":"2026-04-18T22:32:29.813885Z","steps":["trace[1681612499] 'read index received' (duration: 115.409738ms)","trace[1681612499] 'applied index is now lower than readState.Index' (duration: 103.400225ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:32:29.813947Z","caller":"traceutil/trace.go:171","msg":"trace[1163484062] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"233.492168ms","start":"2026-04-18T22:32:29.580429Z","end":"2026-04-18T22:32:29.813921Z","steps":["trace[1163484062] 'process raft request' (duration: 130.165145ms)","trace[1163484062] 'compare' (duration: 102.969934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:32:29.814127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.05224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-18T22:32:29.814169Z","caller":"traceutil/trace.go:171","msg":"trace[1696363519] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8645; }","duration":"219.136322ms","start":"2026-04-18T22:32:29.59502Z","end":"2026-04-18T22:32:29.814156Z","steps":["trace[1696363519] 'agreement among raft nodes before linearized reading' (duration: 218.993398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:29.814187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.632272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-config\" ","response":"range_response_count:1 size:598"} {"level":"warn","ts":"2026-04-18T22:32:29.814188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.861374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-18T22:32:29.814224Z","caller":"traceutil/trace.go:171","msg":"trace[1064050743] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-config; range_end:; response_count:1; response_revision:8645; }","duration":"216.685974ms","start":"2026-04-18T22:32:29.597528Z","end":"2026-04-18T22:32:29.814214Z","steps":["trace[1064050743] 'agreement among raft nodes before linearized reading' (duration: 216.582262ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:29.814234Z","caller":"traceutil/trace.go:171","msg":"trace[1047681201] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8645; }","duration":"218.943657ms","start":"2026-04-18T22:32:29.595277Z","end":"2026-04-18T22:32:29.814221Z","steps":["trace[1047681201] 'agreement among raft nodes before linearized reading' (duration: 218.755581ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:30.32478Z","caller":"traceutil/trace.go:171","msg":"trace[1700493636] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"175.909158ms","start":"2026-04-18T22:32:30.148845Z","end":"2026-04-18T22:32:30.324754Z","steps":["trace[1700493636] 'process raft request' (duration: 175.730333ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:30.324814Z","caller":"traceutil/trace.go:171","msg":"trace[1093332839] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8952; }","duration":"124.465828ms","start":"2026-04-18T22:32:30.200312Z","end":"2026-04-18T22:32:30.324777Z","steps":["trace[1093332839] 'read index received' (duration: 124.146659ms)","trace[1093332839] 'applied index is now lower than readState.Index' (duration: 316.739µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:32:30.325041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.709574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-18T22:32:30.325098Z","caller":"traceutil/trace.go:171","msg":"trace[395642985] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:8651; }","duration":"124.833617ms","start":"2026-04-18T22:32:30.200251Z","end":"2026-04-18T22:32:30.325084Z","steps":["trace[395642985] 'agreement among raft nodes before linearized reading' (duration: 124.628252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:31.040295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.754357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:32:31.040379Z","caller":"traceutil/trace.go:171","msg":"trace[162060572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8655; }","duration":"119.892351ms","start":"2026-04-18T22:32:30.920466Z","end":"2026-04-18T22:32:31.040358Z","steps":["trace[162060572] 'range keys from in-memory index tree' (duration: 119.646325ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:31.040759Z","caller":"traceutil/trace.go:171","msg":"trace[897746925] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"114.75791ms","start":"2026-04-18T22:32:30.925987Z","end":"2026-04-18T22:32:31.040745Z","steps":["trace[897746925] 'process raft request' (duration: 37.929128ms)","trace[897746925] 'compare' (duration: 76.39555ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:32:31.191097Z","caller":"traceutil/trace.go:171","msg":"trace[1625186463] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"145.775946ms","start":"2026-04-18T22:32:31.045286Z","end":"2026-04-18T22:32:31.191062Z","steps":["trace[1625186463] 'process raft request' (duration: 108.846006ms)","trace[1625186463] 'compare' (duration: 36.713974ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:32:31.191517Z","caller":"traceutil/trace.go:171","msg":"trace[546213784] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"144.420169ms","start":"2026-04-18T22:32:31.047075Z","end":"2026-04-18T22:32:31.191495Z","steps":["trace[546213784] 'process raft request' (duration: 143.929265ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:31.191557Z","caller":"traceutil/trace.go:171","msg":"trace[1773926353] linearizableReadLoop","detail":"{readStateIndex:8962; appliedIndex:8959; }","duration":"122.407321ms","start":"2026-04-18T22:32:31.069129Z","end":"2026-04-18T22:32:31.191537Z","steps":["trace[1773926353] 'read index received' (duration: 85.126171ms)","trace[1773926353] 'applied index is now lower than readState.Index' (duration: 37.2803ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:32:31.191804Z","caller":"traceutil/trace.go:171","msg":"trace[1833806904] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"144.701026ms","start":"2026-04-18T22:32:31.047092Z","end":"2026-04-18T22:32:31.191793Z","steps":["trace[1833806904] 'process raft request' (duration: 144.350296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:32:31.191893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.699658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:32:31.191998Z","caller":"traceutil/trace.go:171","msg":"trace[26449151] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8659; }","duration":"122.957026ms","start":"2026-04-18T22:32:31.069006Z","end":"2026-04-18T22:32:31.191963Z","steps":["trace[26449151] 'agreement among raft nodes before linearized reading' (duration: 122.75658ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:41.759732Z","caller":"traceutil/trace.go:171","msg":"trace[1833637339] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"107.882609ms","start":"2026-04-18T22:32:41.651815Z","end":"2026-04-18T22:32:41.759697Z","steps":["trace[1833637339] 'process raft request' (duration: 17.38396ms)","trace[1833637339] 'compare' (duration: 90.332065ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:32:41.760277Z","caller":"traceutil/trace.go:171","msg":"trace[650665772] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"107.599942ms","start":"2026-04-18T22:32:41.652618Z","end":"2026-04-18T22:32:41.760218Z","steps":["trace[650665772] 'process raft request' (duration: 107.367575ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:43.959397Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000239e.wal"} {"level":"info","ts":"2026-04-18T22:34:20.575574Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-18T22:34:20.583012Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-18T22:34:20.583192Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-18T22:36:47.201533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8324} {"level":"info","ts":"2026-04-18T22:36:47.283082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8324,"took":"77.732025ms","hash":3095953057,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":24899584,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-18T22:36:47.283177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3095953057,"revision":8324,"compact-revision":6127} {"level":"info","ts":"2026-04-18T22:40:13.110084Z","caller":"traceutil/trace.go:171","msg":"trace[294066726] linearizableReadLoop","detail":"{readStateIndex:12200; appliedIndex:12199; }","duration":"199.177022ms","start":"2026-04-18T22:40:12.91088Z","end":"2026-04-18T22:40:13.110057Z","steps":["trace[294066726] 'read index received' (duration: 198.956146ms)","trace[294066726] 'applied index is now lower than readState.Index' (duration: 219.206µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:40:13.110155Z","caller":"traceutil/trace.go:171","msg":"trace[301491692] transaction","detail":"{read_only:false; response_revision:11789; number_of_response:1; }","duration":"439.511315ms","start":"2026-04-18T22:40:12.67061Z","end":"2026-04-18T22:40:13.110121Z","steps":["trace[301491692] 'process raft request' (duration: 439.235818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:40:13.110312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:40:12.670588Z","time spent":"439.642558ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:40:13.11036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.46506ms","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-18T22:40:13.110405Z","caller":"traceutil/trace.go:171","msg":"trace[769151664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11789; }","duration":"199.571763ms","start":"2026-04-18T22:40:12.910823Z","end":"2026-04-18T22:40:13.110394Z","steps":["trace[769151664] 'agreement among raft nodes before linearized reading' (duration: 199.391898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:40:13.110562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.339081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:40:13.110677Z","caller":"traceutil/trace.go:171","msg":"trace[781218405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11789; }","duration":"189.467885ms","start":"2026-04-18T22:40:12.921165Z","end":"2026-04-18T22:40:13.110633Z","steps":["trace[781218405] 'agreement among raft nodes before linearized reading' (duration: 189.26799ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:40:18.722308Z","caller":"traceutil/trace.go:171","msg":"trace[1817915186] transaction","detail":"{read_only:false; response_revision:11817; number_of_response:1; }","duration":"329.578024ms","start":"2026-04-18T22:40:18.392679Z","end":"2026-04-18T22:40:18.722257Z","steps":["trace[1817915186] 'process raft request' (duration: 329.41425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:40:18.722496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:40:18.392655Z","time spent":"329.730619ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:40:18.723939Z","caller":"traceutil/trace.go:171","msg":"trace[741183811] linearizableReadLoop","detail":"{readStateIndex:12230; appliedIndex:12228; }","duration":"266.854281ms","start":"2026-04-18T22:40:18.457057Z","end":"2026-04-18T22:40:18.723911Z","steps":["trace[741183811] 'read index received' (duration: 265.02139ms)","trace[741183811] 'applied index is now lower than readState.Index' (duration: 1.831241ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:40:18.724023Z","caller":"traceutil/trace.go:171","msg":"trace[34409824] transaction","detail":"{read_only:false; response_revision:11818; number_of_response:1; }","duration":"327.464407ms","start":"2026-04-18T22:40:18.396521Z","end":"2026-04-18T22:40:18.723986Z","steps":["trace[34409824] 'process raft request' (duration: 327.238611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:40:18.724162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.073188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:40:18.724235Z","caller":"traceutil/trace.go:171","msg":"trace[679379552] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:11818; }","duration":"267.204151ms","start":"2026-04-18T22:40:18.457011Z","end":"2026-04-18T22:40:18.724215Z","steps":["trace[679379552] 'agreement among raft nodes before linearized reading' (duration: 267.059397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:40:18.724318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:40:18.396505Z","time spent":"327.644741ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:40:18.724373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.124729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z26v2\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-18T22:40:18.724373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.959996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-04-18T22:40:18.724439Z","caller":"traceutil/trace.go:171","msg":"trace[521604744] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z26v2; range_end:; response_count:1; response_revision:11818; }","duration":"259.213652ms","start":"2026-04-18T22:40:18.465207Z","end":"2026-04-18T22:40:18.72442Z","steps":["trace[521604744] 'agreement among raft nodes before linearized reading' (duration: 258.918814ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:40:18.724459Z","caller":"traceutil/trace.go:171","msg":"trace[851694203] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11818; }","duration":"183.074049ms","start":"2026-04-18T22:40:18.54137Z","end":"2026-04-18T22:40:18.724444Z","steps":["trace[851694203] 'agreement among raft nodes before linearized reading' (duration: 182.931495ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:41:47.208051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10723} {"level":"info","ts":"2026-04-18T22:41:47.28712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10723,"took":"76.816581ms","hash":1550570556,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":20406272,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-18T22:41:47.287196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1550570556,"revision":10723,"compact-revision":8324}