{"level":"warn","ts":"2026-04-22T00:26:38.98959Z","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-22T00:26:38.989685Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-22T00:26:38.989748Z","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-22T00:26:38.989759Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-22T00:26:38.98978Z","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-22T00:26:38.990404Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-04-22T00:26:38.990476Z","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.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-22T00:26:39.01223Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"20.701332ms"} {"level":"info","ts":"2026-04-22T00:26:39.037212Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-04-22T00:26:39.037347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T00:26:39.037415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-04-22T00:26:39.037455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T00:26:39.037501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-04-22T00:26:39.03757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-04-22T00:26:39.055656Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T00:26:39.05794Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T00:26:39.059993Z","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-22T00:26:39.062947Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T00:26:39.063338Z","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-22T00:26:39.063535Z","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-22T00:26:39.063552Z","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-22T00:26:39.06373Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T00:26:39.065985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-04-22T00:26:39.066246Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-22T00:26:39.069125Z","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-22T00:26:39.069541Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T00:26:39.069594Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T00:26:39.069475Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-22T00:26:39.069721Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-22T00:26:39.338862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T00:26:39.338958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T00:26:39.339074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-04-22T00:26:39.339143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-04-22T00:26:39.339163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-22T00:26:39.339207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-04-22T00:26:39.339232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-22T00:26:39.343428Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T00:26:39.343443Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:26:39.343485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:26:39.343896Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:26:39.344072Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T00:26:39.344109Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T00:26:39.345326Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T00:26:39.348331Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:26:39.348969Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-04-22T00:26:39.349303Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:26:39.349377Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-22T00:26:57.803953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.570402ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-22T00:26:57.804087Z","caller":"traceutil/trace.go:171","msg":"trace[2077936459] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"120.157722ms","start":"2026-04-22T00:26:57.683908Z","end":"2026-04-22T00:26:57.804066Z","steps":["trace[2077936459] 'read index received' (duration: 59.392µs)","trace[2077936459] 'applied index is now lower than readState.Index' (duration: 120.09634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:26:57.804206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.291295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:26:57.804242Z","caller":"traceutil/trace.go:171","msg":"trace[1076127590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"120.378038ms","start":"2026-04-22T00:26:57.683854Z","end":"2026-04-22T00:26:57.804232Z","steps":["trace[1076127590] 'agreement among raft nodes before linearized reading' (duration: 120.273465ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:27:15.681233Z","caller":"traceutil/trace.go:171","msg":"trace[778679787] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"181.504815ms","start":"2026-04-22T00:27:15.499696Z","end":"2026-04-22T00:27:15.6812Z","steps":["trace[778679787] 'process raft request' (duration: 160.814565ms)","trace[778679787] 'compare' (duration: 20.557447ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:27:16.468215Z","caller":"traceutil/trace.go:171","msg":"trace[253877005] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"397.70003ms","start":"2026-04-22T00:27:16.070441Z","end":"2026-04-22T00:27:16.468141Z","steps":["trace[253877005] 'process raft request' (duration: 392.993448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:27:16.469862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:27:16.070406Z","time spent":"397.980247ms","remote":"127.0.0.1:37098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:28:52.237587Z","caller":"traceutil/trace.go:171","msg":"trace[337778825] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"296.887266ms","start":"2026-04-22T00:28:51.940643Z","end":"2026-04-22T00:28:52.23753Z","steps":["trace[337778825] 'process raft request' (duration: 217.68022ms)","trace[337778825] 'compare' (duration: 78.963339ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:28:52.283909Z","caller":"traceutil/trace.go:171","msg":"trace[325941719] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"342.910416ms","start":"2026-04-22T00:28:51.940969Z","end":"2026-04-22T00:28:52.283879Z","steps":["trace[325941719] 'process raft request' (duration: 342.704981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:28:52.284079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:28:51.940954Z","time spent":"343.03336ms","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:28:52.284082Z","caller":"traceutil/trace.go:171","msg":"trace[2017650502] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"340.379319ms","start":"2026-04-22T00:28:51.943657Z","end":"2026-04-22T00:28:52.284036Z","steps":["trace[2017650502] 'process raft request' (duration: 340.155763ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:28:52.28403Z","caller":"traceutil/trace.go:171","msg":"trace[237871990] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1879; }","duration":"123.854619ms","start":"2026-04-22T00:28:52.160137Z","end":"2026-04-22T00:28:52.283992Z","steps":["trace[237871990] 'read index received' (duration: 123.477929ms)","trace[237871990] 'applied index is now lower than readState.Index' (duration: 375.1µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:28:52.284227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.085116ms","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-22T00:28:52.284279Z","caller":"traceutil/trace.go:171","msg":"trace[326042039] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1811; }","duration":"124.176058ms","start":"2026-04-22T00:28:52.160085Z","end":"2026-04-22T00:28:52.284261Z","steps":["trace[326042039] 'agreement among raft nodes before linearized reading' (duration: 124.003124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:28:52.28429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:28:51.943637Z","time spent":"340.520602ms","remote":"127.0.0.1:37188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3726,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:28:52.284598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.016244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T00:28:52.2847Z","caller":"traceutil/trace.go:171","msg":"trace[1993337237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1811; }","duration":"121.21048ms","start":"2026-04-22T00:28:52.163469Z","end":"2026-04-22T00:28:52.284679Z","steps":["trace[1993337237] 'agreement among raft nodes before linearized reading' (duration: 120.880571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:28:52.648172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.094852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:28:52.648262Z","caller":"traceutil/trace.go:171","msg":"trace[2106659592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1814; }","duration":"109.239236ms","start":"2026-04-22T00:28:52.539004Z","end":"2026-04-22T00:28:52.648243Z","steps":["trace[2106659592] 'range keys from in-memory index tree' (duration: 108.888637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:31:07.055382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.466537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:31:07.055568Z","caller":"traceutil/trace.go:171","msg":"trace[398423150] linearizableReadLoop","detail":"{readStateIndex:2897; appliedIndex:2895; }","duration":"179.731192ms","start":"2026-04-22T00:31:06.87582Z","end":"2026-04-22T00:31:07.055551Z","steps":["trace[398423150] 'read index received' (duration: 58.770313ms)","trace[398423150] 'applied index is now lower than readState.Index' (duration: 120.959879ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:31:07.055772Z","caller":"traceutil/trace.go:171","msg":"trace[1735455634] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"292.384056ms","start":"2026-04-22T00:31:06.763358Z","end":"2026-04-22T00:31:07.055742Z","steps":["trace[1735455634] 'process raft request' (duration: 78.471398ms)","trace[1735455634] 'compare' (duration: 213.24803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:31:07.056065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.082751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-22T00:31:07.056114Z","caller":"traceutil/trace.go:171","msg":"trace[2089710537] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2789; }","duration":"173.157004ms","start":"2026-04-22T00:31:06.882942Z","end":"2026-04-22T00:31:07.056099Z","steps":["trace[2089710537] 'agreement among raft nodes before linearized reading' (duration: 173.02891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:31:07.056057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.226676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-22T00:31:07.056301Z","caller":"traceutil/trace.go:171","msg":"trace[337666243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2789; }","duration":"180.526284ms","start":"2026-04-22T00:31:06.875756Z","end":"2026-04-22T00:31:07.056282Z","steps":["trace[337666243] 'agreement among raft nodes before linearized reading' (duration: 180.055511ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:31:07.055797Z","caller":"traceutil/trace.go:171","msg":"trace[184647793] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"271.567792ms","start":"2026-04-22T00:31:06.784205Z","end":"2026-04-22T00:31:07.055773Z","steps":["trace[184647793] 'process raft request' (duration: 271.286304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:31:07.056175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.738326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T00:31:07.056659Z","caller":"traceutil/trace.go:171","msg":"trace[1825651530] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2789; }","duration":"144.278492ms","start":"2026-04-22T00:31:06.912362Z","end":"2026-04-22T00:31:07.056641Z","steps":["trace[1825651530] 'agreement among raft nodes before linearized reading' (duration: 143.675035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:31:07.05632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.850629ms","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-22T00:31:07.056822Z","caller":"traceutil/trace.go:171","msg":"trace[758779179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2789; }","duration":"155.365213ms","start":"2026-04-22T00:31:06.901411Z","end":"2026-04-22T00:31:07.056777Z","steps":["trace[758779179] 'agreement among raft nodes before linearized reading' (duration: 154.796997ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:35:01.846952Z","caller":"traceutil/trace.go:171","msg":"trace[2075147358] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"103.198212ms","start":"2026-04-22T00:35:01.743721Z","end":"2026-04-22T00:35:01.84692Z","steps":["trace[2075147358] 'process raft request' (duration: 102.957496ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:35:01.854688Z","caller":"traceutil/trace.go:171","msg":"trace[1348219653] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"109.857184ms","start":"2026-04-22T00:35:01.744807Z","end":"2026-04-22T00:35:01.854665Z","steps":["trace[1348219653] 'process raft request' (duration: 109.462603ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:35:20.54828Z","caller":"traceutil/trace.go:171","msg":"trace[732376661] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"189.660642ms","start":"2026-04-22T00:35:20.358586Z","end":"2026-04-22T00:35:20.548247Z","steps":["trace[732376661] 'process raft request' (duration: 112.648612ms)","trace[732376661] 'compare' (duration: 76.858106ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:35:20.579407Z","caller":"traceutil/trace.go:171","msg":"trace[1290131752] linearizableReadLoop","detail":"{readStateIndex:5534; appliedIndex:5532; }","duration":"110.727171ms","start":"2026-04-22T00:35:20.468659Z","end":"2026-04-22T00:35:20.579386Z","steps":["trace[1290131752] 'read index received' (duration: 2.588089ms)","trace[1290131752] 'applied index is now lower than readState.Index' (duration: 108.137452ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:35:20.57948Z","caller":"traceutil/trace.go:171","msg":"trace[213400174] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"220.356913ms","start":"2026-04-22T00:35:20.359098Z","end":"2026-04-22T00:35:20.579455Z","steps":["trace[213400174] 'process raft request' (duration: 220.149568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:35:20.579531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.856884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:35:20.579573Z","caller":"traceutil/trace.go:171","msg":"trace[245291037] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5334; }","duration":"110.981568ms","start":"2026-04-22T00:35:20.46858Z","end":"2026-04-22T00:35:20.579562Z","steps":["trace[245291037] 'agreement among raft nodes before linearized reading' (duration: 110.892096ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:35:21.061889Z","caller":"traceutil/trace.go:171","msg":"trace[1516345575] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"127.259123ms","start":"2026-04-22T00:35:20.934583Z","end":"2026-04-22T00:35:21.061843Z","steps":["trace[1516345575] 'process raft request' (duration: 127.005866ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:36:39.370277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-04-22T00:36:39.445578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"73.627208ms","hash":3910026409,"current-db-size-bytes":57749504,"current-db-size":"58 MB","current-db-size-in-use-bytes":33447936,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-22T00:36:39.445654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3910026409,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-04-22T00:41:14.179621Z","caller":"traceutil/trace.go:171","msg":"trace[245007702] transaction","detail":"{read_only:false; response_revision:8118; number_of_response:1; }","duration":"130.127604ms","start":"2026-04-22T00:41:14.049468Z","end":"2026-04-22T00:41:14.179596Z","steps":["trace[245007702] 'process raft request' (duration: 129.98534ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:41:25.734409Z","caller":"traceutil/trace.go:171","msg":"trace[922852382] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"244.286836ms","start":"2026-04-22T00:41:25.490084Z","end":"2026-04-22T00:41:25.734371Z","steps":["trace[922852382] 'process raft request' (duration: 242.827656ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:41:25.861165Z","caller":"traceutil/trace.go:171","msg":"trace[899111434] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"105.647392ms","start":"2026-04-22T00:41:25.755488Z","end":"2026-04-22T00:41:25.861135Z","steps":["trace[899111434] 'process raft request' (duration: 81.719203ms)","trace[899111434] 'compare' (duration: 23.798625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:41:26.729663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.769009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:41:26.729956Z","caller":"traceutil/trace.go:171","msg":"trace[1522251479] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"251.628315ms","start":"2026-04-22T00:41:26.478282Z","end":"2026-04-22T00:41:26.729911Z","steps":["trace[1522251479] 'process raft request' (duration: 47.520917ms)","trace[1522251479] 'compare' (duration: 203.619345ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:41:26.730042Z","caller":"traceutil/trace.go:171","msg":"trace[1558843548] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"251.495571ms","start":"2026-04-22T00:41:26.478513Z","end":"2026-04-22T00:41:26.730009Z","steps":["trace[1558843548] 'process raft request' (duration: 251.262585ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:41:26.73Z","caller":"traceutil/trace.go:171","msg":"trace[566410851] linearizableReadLoop","detail":"{readStateIndex:8474; appliedIndex:8472; }","duration":"251.118711ms","start":"2026-04-22T00:41:26.478857Z","end":"2026-04-22T00:41:26.729976Z","steps":["trace[566410851] 'read index received' (duration: 46.949421ms)","trace[566410851] 'applied index is now lower than readState.Index' (duration: 204.16768ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:41:26.730164Z","caller":"traceutil/trace.go:171","msg":"trace[815418267] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"240.140833ms","start":"2026-04-22T00:41:26.489996Z","end":"2026-04-22T00:41:26.730137Z","steps":["trace[815418267] 'process raft request' (duration: 239.911707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:41:26.730725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.85106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223823"} {"level":"info","ts":"2026-04-22T00:41:26.730798Z","caller":"traceutil/trace.go:171","msg":"trace[1676047478] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8186; }","duration":"251.939212ms","start":"2026-04-22T00:41:26.478841Z","end":"2026-04-22T00:41:26.730781Z","steps":["trace[1676047478] 'agreement among raft nodes before linearized reading' (duration: 251.199922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:41:27.802455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.439554ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-22T00:41:28.055908Z","caller":"traceutil/trace.go:171","msg":"trace[1688048647] linearizableReadLoop","detail":"{readStateIndex:8479; appliedIndex:8478; }","duration":"186.4644ms","start":"2026-04-22T00:41:27.869413Z","end":"2026-04-22T00:41:28.055877Z","steps":["trace[1688048647] 'read index received' (duration: 186.221893ms)","trace[1688048647] 'applied index is now lower than readState.Index' (duration: 241.467µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:41:28.05599Z","caller":"traceutil/trace.go:171","msg":"trace[819610549] transaction","detail":"{read_only:false; response_revision:8189; number_of_response:1; }","duration":"187.287352ms","start":"2026-04-22T00:41:27.868684Z","end":"2026-04-22T00:41:28.055971Z","steps":["trace[819610549] 'process raft request' (duration: 186.954142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:41:28.056226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.499493ms","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-22T00:41:28.056105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.677836ms","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-22T00:41:28.056256Z","caller":"traceutil/trace.go:171","msg":"trace[1349304074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8189; }","duration":"151.580226ms","start":"2026-04-22T00:41:27.904667Z","end":"2026-04-22T00:41:28.056247Z","steps":["trace[1349304074] 'agreement among raft nodes before linearized reading' (duration: 151.469073ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:41:28.056288Z","caller":"traceutil/trace.go:171","msg":"trace[1507734862] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8189; }","duration":"186.900942ms","start":"2026-04-22T00:41:27.869362Z","end":"2026-04-22T00:41:28.056263Z","steps":["trace[1507734862] 'agreement among raft nodes before linearized reading' (duration: 186.685246ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:41:39.376736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6075} {"level":"info","ts":"2026-04-22T00:41:39.474913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6075,"took":"96.180138ms","hash":3163105486,"current-db-size-bytes":57749504,"current-db-size":"58 MB","current-db-size-in-use-bytes":23179264,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-22T00:41:39.474993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3163105486,"revision":6075,"compact-revision":2967} {"level":"warn","ts":"2026-04-22T00:41:54.649157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.547248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-22T00:41:54.649218Z","caller":"traceutil/trace.go:171","msg":"trace[1401978608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8387; }","duration":"272.680261ms","start":"2026-04-22T00:41:54.376526Z","end":"2026-04-22T00:41:54.649206Z","steps":["trace[1401978608] 'range keys from in-memory index tree' (duration: 272.412194ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:42:07.24763Z","caller":"traceutil/trace.go:171","msg":"trace[1322755106] linearizableReadLoop","detail":"{readStateIndex:8801; appliedIndex:8800; }","duration":"294.414711ms","start":"2026-04-22T00:42:06.95319Z","end":"2026-04-22T00:42:07.247605Z","steps":["trace[1322755106] 'read index received' (duration: 201.0823ms)","trace[1322755106] 'applied index is now lower than readState.Index' (duration: 93.330961ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:42:07.247893Z","caller":"traceutil/trace.go:171","msg":"trace[226470268] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"296.21222ms","start":"2026-04-22T00:42:06.951627Z","end":"2026-04-22T00:42:07.247839Z","steps":["trace[226470268] 'process raft request' (duration: 202.807677ms)","trace[226470268] 'compare' (duration: 92.973861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:42:07.248102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.294985ms","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-22T00:42:07.248191Z","caller":"traceutil/trace.go:171","msg":"trace[1914747521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8498; }","duration":"257.448699ms","start":"2026-04-22T00:42:06.990721Z","end":"2026-04-22T00:42:07.248169Z","steps":["trace[1914747521] 'agreement among raft nodes before linearized reading' (duration: 257.190472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:42:07.24847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.270225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-22T00:42:07.248532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.4225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wwc58\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-22T00:42:07.248637Z","caller":"traceutil/trace.go:171","msg":"trace[1184031775] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wwc58; range_end:; response_count:1; response_revision:8498; }","duration":"246.557794ms","start":"2026-04-22T00:42:07.002059Z","end":"2026-04-22T00:42:07.248617Z","steps":["trace[1184031775] 'agreement among raft nodes before linearized reading' (duration: 246.330208ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:42:07.248583Z","caller":"traceutil/trace.go:171","msg":"trace[2029587137] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8498; }","duration":"295.411719ms","start":"2026-04-22T00:42:06.95313Z","end":"2026-04-22T00:42:07.248541Z","steps":["trace[2029587137] 'agreement among raft nodes before linearized reading' (duration: 294.685939ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:42:07.835991Z","caller":"traceutil/trace.go:171","msg":"trace[694004174] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"102.018716ms","start":"2026-04-22T00:42:07.73394Z","end":"2026-04-22T00:42:07.835958Z","steps":["trace[694004174] 'process raft request' (duration: 101.833201ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:42:08.140207Z","caller":"traceutil/trace.go:171","msg":"trace[1538601491] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"231.002841ms","start":"2026-04-22T00:42:07.909179Z","end":"2026-04-22T00:42:08.140182Z","steps":["trace[1538601491] 'process raft request' (duration: 230.819416ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:42:08.241791Z","caller":"traceutil/trace.go:171","msg":"trace[1476267761] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"288.280403ms","start":"2026-04-22T00:42:07.953481Z","end":"2026-04-22T00:42:08.241761Z","steps":["trace[1476267761] 'process raft request' (duration: 243.917272ms)","trace[1476267761] 'compare' (duration: 44.156536ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:43:04.923164Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ae.wal"} {"level":"warn","ts":"2026-04-22T00:44:10.934394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696255695690172,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:44:11.434964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696255695690172,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:44:11.935269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696255695690172,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:44:12.436435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696255695690172,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:44:12.871933Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.500748422s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T00:44:12.878708Z","caller":"traceutil/trace.go:171","msg":"trace[35803777] transaction","detail":"{read_only:false; response_revision:9601; number_of_response:1; }","duration":"2.507567687s","start":"2026-04-22T00:44:10.371101Z","end":"2026-04-22T00:44:12.878668Z","steps":["trace[35803777] 'process raft request' (duration: 2.501099511s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.87886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:10.371075Z","time spent":"2.507710202s","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:44:12.882663Z","caller":"traceutil/trace.go:171","msg":"trace[1457696487] transaction","detail":"{read_only:false; response_revision:9604; number_of_response:1; }","duration":"1.523775877s","start":"2026-04-22T00:44:11.358861Z","end":"2026-04-22T00:44:12.882637Z","steps":["trace[1457696487] 'process raft request' (duration: 1.523710255s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.882807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.358843Z","time spent":"1.523876119s","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:44:12.882798Z","caller":"traceutil/trace.go:171","msg":"trace[1946154852] transaction","detail":"{read_only:false; response_revision:9602; number_of_response:1; }","duration":"2.214070997s","start":"2026-04-22T00:44:10.668699Z","end":"2026-04-22T00:44:12.88277Z","steps":["trace[1946154852] 'process raft request' (duration: 2.213720718s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:44:12.882864Z","caller":"traceutil/trace.go:171","msg":"trace[1415376210] transaction","detail":"{read_only:false; response_revision:9605; number_of_response:1; }","duration":"420.088974ms","start":"2026-04-22T00:44:12.462731Z","end":"2026-04-22T00:44:12.88282Z","steps":["trace[1415376210] 'process raft request' (duration: 419.872618ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:44:12.882877Z","caller":"traceutil/trace.go:171","msg":"trace[1546289887] transaction","detail":"{read_only:false; response_revision:9603; number_of_response:1; }","duration":"2.19570384s","start":"2026-04-22T00:44:10.68714Z","end":"2026-04-22T00:44:12.882844Z","steps":["trace[1546289887] 'process raft request' (duration: 2.195396542s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.882944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.448963058s","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-22T00:44:12.882988Z","caller":"traceutil/trace.go:171","msg":"trace[2047545391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9605; }","duration":"2.449051691s","start":"2026-04-22T00:44:10.433926Z","end":"2026-04-22T00:44:12.882978Z","steps":["trace[2047545391] 'agreement among raft nodes before linearized reading' (duration: 2.448908307s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.882896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:10.66867Z","time spent":"2.214183531s","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:44:12.883017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:10.433911Z","time spent":"2.449099062s","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:44:12.883016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:12.462701Z","time spent":"420.235428ms","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:44:12.883034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:10.687116Z","time spent":"2.195836053s","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:44:12.882789Z","caller":"traceutil/trace.go:171","msg":"trace[647167184] linearizableReadLoop","detail":"{readStateIndex:9937; appliedIndex:9933; }","duration":"2.448793033s","start":"2026-04-22T00:44:10.433972Z","end":"2026-04-22T00:44:12.882765Z","steps":["trace[647167184] 'read index received' (duration: 2.438229686s)","trace[647167184] 'applied index is now lower than readState.Index' (duration: 10.562597ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:44:12.883244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002082248s","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-22T00:44:12.883291Z","caller":"traceutil/trace.go:171","msg":"trace[1782685058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9605; }","duration":"2.00215288s","start":"2026-04-22T00:44:10.881125Z","end":"2026-04-22T00:44:12.883278Z","steps":["trace[1782685058] 'agreement among raft nodes before linearized reading' (duration: 2.002014116s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.8833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01654734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-22T00:44:12.883312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.214653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-22T00:44:12.883249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.499017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:44:12.883395Z","caller":"traceutil/trace.go:171","msg":"trace[615942562] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:9605; }","duration":"228.657331ms","start":"2026-04-22T00:44:12.654691Z","end":"2026-04-22T00:44:12.883348Z","steps":["trace[615942562] 'agreement among raft nodes before linearized reading' (duration: 228.492886ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:44:12.883395Z","caller":"traceutil/trace.go:171","msg":"trace[2065800225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9605; }","duration":"1.016610061s","start":"2026-04-22T00:44:11.866727Z","end":"2026-04-22T00:44:12.883338Z","steps":["trace[2065800225] 'agreement among raft nodes before linearized reading' (duration: 1.016458437s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.883334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.862703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wwc58\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-04-22T00:44:12.88342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644323435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:44:12.883445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.86672Z","time spent":"1.016713954s","remote":"127.0.0.1:37268","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":"info","ts":"2026-04-22T00:44:12.883454Z","caller":"traceutil/trace.go:171","msg":"trace[2136369734] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wwc58; range_end:; response_count:1; response_revision:9605; }","duration":"858.975976ms","start":"2026-04-22T00:44:12.024462Z","end":"2026-04-22T00:44:12.883438Z","steps":["trace[2136369734] 'agreement among raft nodes before linearized reading' (duration: 858.757ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:44:12.883487Z","caller":"traceutil/trace.go:171","msg":"trace[11733602] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:9605; }","duration":"477.301525ms","start":"2026-04-22T00:44:12.406042Z","end":"2026-04-22T00:44:12.883343Z","steps":["trace[11733602] 'agreement among raft nodes before linearized reading' (duration: 477.198682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.883495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:12.024398Z","time spent":"859.088418ms","remote":"127.0.0.1:37188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-wwc58\" "} {"level":"warn","ts":"2026-04-22T00:44:12.883458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019285264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:44:12.883517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:12.406009Z","time spent":"477.50098ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"warn","ts":"2026-04-22T00:44:12.883516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.703501801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-22T00:44:12.883546Z","caller":"traceutil/trace.go:171","msg":"trace[1835678526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9605; }","duration":"1.019400378s","start":"2026-04-22T00:44:11.864135Z","end":"2026-04-22T00:44:12.883536Z","steps":["trace[1835678526] 'agreement among raft nodes before linearized reading' (duration: 1.019304785s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.883425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475428625s","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-22T00:44:12.883557Z","caller":"traceutil/trace.go:171","msg":"trace[935778771] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9605; }","duration":"1.703579443s","start":"2026-04-22T00:44:11.179966Z","end":"2026-04-22T00:44:12.883545Z","steps":["trace[935778771] 'agreement among raft nodes before linearized reading' (duration: 1.70347281s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.883534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608768761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:44:12.883581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.864116Z","time spent":"1.019458069s","remote":"127.0.0.1:36984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:44:12.883503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158811888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T00:44:12.883596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.179945Z","time spent":"1.703642794s","remote":"127.0.0.1:37188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-22T00:44:12.883602Z","caller":"traceutil/trace.go:171","msg":"trace[1317391096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9605; }","duration":"1.47562907s","start":"2026-04-22T00:44:11.407952Z","end":"2026-04-22T00:44:12.883581Z","steps":["trace[1317391096] 'agreement among raft nodes before linearized reading' (duration: 1.475396054s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:44:12.883619Z","caller":"traceutil/trace.go:171","msg":"trace[1451204914] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:9605; }","duration":"1.608898165s","start":"2026-04-22T00:44:11.274703Z","end":"2026-04-22T00:44:12.883601Z","steps":["trace[1451204914] 'agreement among raft nodes before linearized reading' (duration: 1.60873907s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:44:12.88346Z","caller":"traceutil/trace.go:171","msg":"trace[1857499395] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:9605; }","duration":"1.644400037s","start":"2026-04-22T00:44:11.239049Z","end":"2026-04-22T00:44:12.883449Z","steps":["trace[1857499395] 'agreement among raft nodes before linearized reading' (duration: 1.644302085s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.883325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:10.881108Z","time spent":"2.002209161s","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T00:44:12.883657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.27469Z","time spent":"1.608954186s","remote":"127.0.0.1:49660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-22T00:44:12.883668Z","caller":"traceutil/trace.go:171","msg":"trace[241941274] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:9605; }","duration":"1.159011143s","start":"2026-04-22T00:44:11.724641Z","end":"2026-04-22T00:44:12.883652Z","steps":["trace[241941274] 'agreement among raft nodes before linearized reading' (duration: 1.158832608s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.883673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.239029Z","time spent":"1.644632904s","remote":"127.0.0.1:48360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-04-22T00:44:12.883657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.407933Z","time spent":"1.475712162s","remote":"127.0.0.1:37268","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-22T00:44:12.883713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.724619Z","time spent":"1.159080085s","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-22T00:44:12.883563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444593578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-22T00:44:12.883937Z","caller":"traceutil/trace.go:171","msg":"trace[297924478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9605; }","duration":"1.444981309s","start":"2026-04-22T00:44:11.438942Z","end":"2026-04-22T00:44:12.883923Z","steps":["trace[297924478] 'agreement among raft nodes before linearized reading' (duration: 1.444575417s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.883979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.438931Z","time spent":"1.445036171s","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T00:44:12.884202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.330071ms","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-22T00:44:12.884251Z","caller":"traceutil/trace.go:171","msg":"trace[722823745] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9605; }","duration":"944.419404ms","start":"2026-04-22T00:44:11.939817Z","end":"2026-04-22T00:44:12.884236Z","steps":["trace[722823745] 'agreement among raft nodes before linearized reading' (duration: 943.453757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:44:12.88428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:44:11.939796Z","time spent":"944.476556ms","remote":"127.0.0.1:37268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T00:44:22.922517Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"344940fc142d902f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T00:44:22.928502Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T00:44:22.92871Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-22T00:46:39.38298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8257} {"level":"info","ts":"2026-04-22T00:46:39.466365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8257,"took":"81.224125ms","hash":1797835336,"current-db-size-bytes":57749504,"current-db-size":"58 MB","current-db-size-in-use-bytes":24293376,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-22T00:46:39.466531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1797835336,"revision":8257,"compact-revision":6075} {"level":"info","ts":"2026-04-22T00:51:39.389867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10635} {"level":"info","ts":"2026-04-22T00:51:39.469738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10635,"took":"78.14474ms","hash":933959364,"current-db-size-bytes":57749504,"current-db-size":"58 MB","current-db-size-in-use-bytes":20033536,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-22T00:51:39.469792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":933959364,"revision":10635,"compact-revision":8257}