{"level":"warn","ts":"2026-04-16T16:32:16.428871Z","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-16T16:32:16.428956Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-16T16:32:16.429032Z","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-16T16:32:16.429053Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-16T16:32:16.42908Z","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-16T16:32:16.429867Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-16T16:32:16.429954Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-16T16:32:16.434029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.72785ms"} {"level":"info","ts":"2026-04-16T16:32:16.44122Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-16T16:32:16.441336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:32:16.441363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-16T16:32:16.441379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:32:16.441389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-16T16:32:16.441448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-16T16:32:16.447374Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:32:16.449232Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:32:16.451217Z","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-16T16:32:16.453962Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:32:16.454377Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:32:16.454532Z","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-16T16:32:16.454621Z","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-16T16:32:16.454633Z","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-16T16:32:16.456558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-16T16:32:16.457137Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-16T16:32:16.460418Z","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-16T16:32:16.460555Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-16T16:32:16.460587Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-16T16:32:16.460741Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:32:16.460786Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:32:16.642236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:32:16.642283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:32:16.642309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-16T16:32:16.642336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:32:16.642343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-16T16:32:16.642353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-16T16:32:16.642362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-16T16:32:16.643324Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:32:16.644167Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:32:16.644325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:32:16.64439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:32:16.644683Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:32:16.644757Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:32:16.644776Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:32:16.647811Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-16T16:32:16.647967Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:32:16.64794Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:32:16.648115Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T16:33:00.048406Z","caller":"traceutil/trace.go:171","msg":"trace[1499723544] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"109.788383ms","start":"2026-04-16T16:32:59.938585Z","end":"2026-04-16T16:33:00.048374Z","steps":["trace[1499723544] 'read index received' (duration: 105.602352ms)","trace[1499723544] 'applied index is now lower than readState.Index' (duration: 4.184461ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:00.048491Z","caller":"traceutil/trace.go:171","msg":"trace[2096611488] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"137.228302ms","start":"2026-04-16T16:32:59.911208Z","end":"2026-04-16T16:33:00.048436Z","steps":["trace[2096611488] 'process raft request' (duration: 132.935909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:00.048603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.993908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:00.048718Z","caller":"traceutil/trace.go:171","msg":"trace[1930309090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"110.170162ms","start":"2026-04-16T16:32:59.938527Z","end":"2026-04-16T16:33:00.048697Z","steps":["trace[1930309090] 'agreement among raft nodes before linearized reading' (duration: 109.977448ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:00.680403Z","caller":"traceutil/trace.go:171","msg":"trace[112976162] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"140.05656ms","start":"2026-04-16T16:33:00.540313Z","end":"2026-04-16T16:33:00.68037Z","steps":["trace[112976162] 'process raft request' (duration: 139.393383ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:01.108029Z","caller":"traceutil/trace.go:171","msg":"trace[508644432] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"216.538254ms","start":"2026-04-16T16:33:00.891462Z","end":"2026-04-16T16:33:01.108001Z","steps":["trace[508644432] 'read index received' (duration: 216.34056ms)","trace[508644432] 'applied index is now lower than readState.Index' (duration: 196.374µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:01.108069Z","caller":"traceutil/trace.go:171","msg":"trace[1244266258] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"371.398219ms","start":"2026-04-16T16:33:00.736647Z","end":"2026-04-16T16:33:01.108045Z","steps":["trace[1244266258] 'process raft request' (duration: 371.207485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:01.108314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.355436ms","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-16T16:33:01.10837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.886322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vm7tx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T16:33:01.108398Z","caller":"traceutil/trace.go:171","msg":"trace[1126878777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"200.488499ms","start":"2026-04-16T16:33:00.907889Z","end":"2026-04-16T16:33:01.108377Z","steps":["trace[1126878777] 'agreement among raft nodes before linearized reading' (duration: 200.289624ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:01.108424Z","caller":"traceutil/trace.go:171","msg":"trace[1214002162] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vm7tx; range_end:; response_count:1; response_revision:637; }","duration":"216.979205ms","start":"2026-04-16T16:33:00.89143Z","end":"2026-04-16T16:33:01.108409Z","steps":["trace[1214002162] 'agreement among raft nodes before linearized reading' (duration: 216.716589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:01.108545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:00.736617Z","time spent":"371.597763ms","remote":"127.0.0.1:33192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:34:32.258633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.536537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74c34d6e-fad3-429c-a591-53c31635897b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-16T16:34:32.258731Z","caller":"traceutil/trace.go:171","msg":"trace[114067563] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-74c34d6e-fad3-429c-a591-53c31635897b; range_end:; response_count:1; response_revision:1864; }","duration":"281.717471ms","start":"2026-04-16T16:34:31.976992Z","end":"2026-04-16T16:34:32.25871Z","steps":["trace[114067563] 'range keys from in-memory index tree' (duration: 281.336872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:32.258796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.269861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T16:34:32.2589Z","caller":"traceutil/trace.go:171","msg":"trace[1965383739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1864; }","duration":"165.411124ms","start":"2026-04-16T16:34:32.093464Z","end":"2026-04-16T16:34:32.258875Z","steps":["trace[1965383739] 'range keys from in-memory index tree' (duration: 165.103116ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:46.557659Z","caller":"traceutil/trace.go:171","msg":"trace[1140141002] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"190.021369ms","start":"2026-04-16T16:34:46.367562Z","end":"2026-04-16T16:34:46.557584Z","steps":["trace[1140141002] 'process raft request' (duration: 175.743924ms)","trace[1140141002] 'compare' (duration: 13.943657ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:34:49.578562Z","caller":"traceutil/trace.go:171","msg":"trace[192455712] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"127.13502ms","start":"2026-04-16T16:34:49.451403Z","end":"2026-04-16T16:34:49.578538Z","steps":["trace[192455712] 'process raft request' (duration: 126.976397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:23.377836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.261435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:35:23.378177Z","caller":"traceutil/trace.go:171","msg":"trace[1276700347] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"413.679462ms","start":"2026-04-16T16:35:22.964459Z","end":"2026-04-16T16:35:23.378138Z","steps":["trace[1276700347] 'process raft request' (duration: 151.798622ms)","trace[1276700347] 'compare' (duration: 261.123372ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:35:23.378297Z","caller":"traceutil/trace.go:171","msg":"trace[266380977] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"128.22873ms","start":"2026-04-16T16:35:23.25005Z","end":"2026-04-16T16:35:23.378279Z","steps":["trace[266380977] 'process raft request' (duration: 127.983444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:23.378338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:22.964445Z","time spent":"413.808305ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:35:23.378295Z","caller":"traceutil/trace.go:171","msg":"trace[679103119] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"291.340465ms","start":"2026-04-16T16:35:23.086903Z","end":"2026-04-16T16:35:23.378244Z","steps":["trace[679103119] 'read index received' (duration: 29.423493ms)","trace[679103119] 'applied index is now lower than readState.Index' (duration: 261.913492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:35:23.378592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.672383ms","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-16T16:35:23.378695Z","caller":"traceutil/trace.go:171","msg":"trace[1657573987] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"291.812357ms","start":"2026-04-16T16:35:23.086851Z","end":"2026-04-16T16:35:23.378663Z","steps":["trace[1657573987] 'agreement among raft nodes before linearized reading' (duration: 291.51716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:23.378725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.81036ms","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-16T16:35:23.378799Z","caller":"traceutil/trace.go:171","msg":"trace[899433628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2189; }","duration":"153.951994ms","start":"2026-04-16T16:35:23.22483Z","end":"2026-04-16T16:35:23.378782Z","steps":["trace[899433628] 'agreement among raft nodes before linearized reading' (duration: 153.763759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:23.378868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.405683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:35:23.37893Z","caller":"traceutil/trace.go:171","msg":"trace[1609640455] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2189; }","duration":"208.506566ms","start":"2026-04-16T16:35:23.170405Z","end":"2026-04-16T16:35:23.378912Z","steps":["trace[1609640455] 'agreement among raft nodes before linearized reading' (duration: 208.406553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:23.379193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.968467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-16T16:35:23.379571Z","caller":"traceutil/trace.go:171","msg":"trace[429139194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2189; }","duration":"242.392089ms","start":"2026-04-16T16:35:23.137144Z","end":"2026-04-16T16:35:23.379536Z","steps":["trace[429139194] 'agreement among raft nodes before linearized reading' (duration: 241.389074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:23.658259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.825197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T16:35:23.658331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.152548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:35:23.658386Z","caller":"traceutil/trace.go:171","msg":"trace[780526693] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2191; }","duration":"165.045172ms","start":"2026-04-16T16:35:23.493315Z","end":"2026-04-16T16:35:23.65836Z","steps":["trace[780526693] 'count revisions from in-memory index tree' (duration: 164.693984ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:23.658397Z","caller":"traceutil/trace.go:171","msg":"trace[1167825129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2191; }","duration":"147.277772ms","start":"2026-04-16T16:35:23.511103Z","end":"2026-04-16T16:35:23.658381Z","steps":["trace[1167825129] 'count revisions from in-memory index tree' (duration: 147.002615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:40:36.513814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.454803ms","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-16T16:40:36.514459Z","caller":"traceutil/trace.go:171","msg":"trace[80051789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4980; }","duration":"178.147049ms","start":"2026-04-16T16:40:36.336253Z","end":"2026-04-16T16:40:36.5144Z","steps":["trace[80051789] 'range keys from in-memory index tree' (duration: 177.259368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:40:53.598153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.64075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-16T16:40:53.598225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.614471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cb0995c3-3c9c-4614-a5c6-c68c1da6823a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-16T16:40:53.598283Z","caller":"traceutil/trace.go:171","msg":"trace[1292210160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5322; }","duration":"111.823195ms","start":"2026-04-16T16:40:53.486433Z","end":"2026-04-16T16:40:53.598256Z","steps":["trace[1292210160] 'range keys from in-memory index tree' (duration: 111.379465ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:40:53.598347Z","caller":"traceutil/trace.go:171","msg":"trace[152648982] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cb0995c3-3c9c-4614-a5c6-c68c1da6823a; range_end:; response_count:1; response_revision:5322; }","duration":"171.788605ms","start":"2026-04-16T16:40:53.426522Z","end":"2026-04-16T16:40:53.598311Z","steps":["trace[152648982] 'range keys from in-memory index tree' (duration: 171.387766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:40:53.897881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.212954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:40:53.89807Z","caller":"traceutil/trace.go:171","msg":"trace[1823297996] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"293.574202ms","start":"2026-04-16T16:40:53.604468Z","end":"2026-04-16T16:40:53.898042Z","steps":["trace[1823297996] 'process raft request' (duration: 186.111312ms)","trace[1823297996] 'compare' (duration: 107.066681ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:16.802858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-04-16T16:42:16.888296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"83.514213ms","hash":2808214211,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":31846400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-16T16:42:16.888371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2808214211,"revision":3001,"compact-revision":-1} {"level":"warn","ts":"2026-04-16T16:47:05.10354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.735702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:47:05.103679Z","caller":"traceutil/trace.go:171","msg":"trace[352775858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8226; }","duration":"154.893035ms","start":"2026-04-16T16:47:04.948758Z","end":"2026-04-16T16:47:05.103651Z","steps":["trace[352775858] 'range keys from in-memory index tree' (duration: 154.573157ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:05.841985Z","caller":"traceutil/trace.go:171","msg":"trace[1421180738] linearizableReadLoop","detail":"{readStateIndex:8518; appliedIndex:8517; }","duration":"248.057876ms","start":"2026-04-16T16:47:05.593901Z","end":"2026-04-16T16:47:05.841959Z","steps":["trace[1421180738] 'read index received' (duration: 247.869462ms)","trace[1421180738] 'applied index is now lower than readState.Index' (duration: 187.194µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:47:05.84216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.23211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:47:05.842204Z","caller":"traceutil/trace.go:171","msg":"trace[1461373850] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8229; }","duration":"248.316532ms","start":"2026-04-16T16:47:05.593873Z","end":"2026-04-16T16:47:05.842189Z","steps":["trace[1461373850] 'agreement among raft nodes before linearized reading' (duration: 248.206539ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:05.842238Z","caller":"traceutil/trace.go:171","msg":"trace[1602147456] transaction","detail":"{read_only:false; response_revision:8229; number_of_response:1; }","duration":"262.893342ms","start":"2026-04-16T16:47:05.579276Z","end":"2026-04-16T16:47:05.842169Z","steps":["trace[1602147456] 'process raft request' (duration: 262.532384ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:16.808739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6119} {"level":"info","ts":"2026-04-16T16:47:16.902443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6119,"took":"91.774587ms","hash":1248684857,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":23138304,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-16T16:47:16.902505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1248684857,"revision":6119,"compact-revision":3001} {"level":"info","ts":"2026-04-16T16:47:35.516642Z","caller":"traceutil/trace.go:171","msg":"trace[665537956] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"107.031895ms","start":"2026-04-16T16:47:35.409582Z","end":"2026-04-16T16:47:35.516614Z","steps":["trace[665537956] 'process raft request' (duration: 106.86626ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:46.385942Z","caller":"traceutil/trace.go:171","msg":"trace[1935385664] linearizableReadLoop","detail":"{readStateIndex:8846; appliedIndex:8845; }","duration":"235.422042ms","start":"2026-04-16T16:47:46.150496Z","end":"2026-04-16T16:47:46.385918Z","steps":["trace[1935385664] 'read index received' (duration: 153.719416ms)","trace[1935385664] 'applied index is now lower than readState.Index' (duration: 81.701296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:47:46.386269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.746881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-bbr29\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-04-16T16:47:46.386348Z","caller":"traceutil/trace.go:171","msg":"trace[615377329] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-bbr29; range_end:; response_count:1; response_revision:8542; }","duration":"236.036297ms","start":"2026-04-16T16:47:46.150296Z","end":"2026-04-16T16:47:46.386332Z","steps":["trace[615377329] 'agreement among raft nodes before linearized reading' (duration: 235.819272ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:46.386436Z","caller":"traceutil/trace.go:171","msg":"trace[126438958] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"236.341074ms","start":"2026-04-16T16:47:46.15006Z","end":"2026-04-16T16:47:46.386401Z","steps":["trace[126438958] 'process raft request' (duration: 154.236219ms)","trace[126438958] 'compare' (duration: 81.51033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:47:47.103185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.893786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:47:47.10328Z","caller":"traceutil/trace.go:171","msg":"trace[979230773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8547; }","duration":"165.049039ms","start":"2026-04-16T16:47:46.938211Z","end":"2026-04-16T16:47:47.10326Z","steps":["trace[979230773] 'range keys from in-memory index tree' (duration: 164.770552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:47:47.103388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.279926ms","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-16T16:47:47.103499Z","caller":"traceutil/trace.go:171","msg":"trace[199414324] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8547; }","duration":"269.397898ms","start":"2026-04-16T16:47:46.834075Z","end":"2026-04-16T16:47:47.103473Z","steps":["trace[199414324] 'range keys from in-memory index tree' (duration: 269.085931ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:47.222565Z","caller":"traceutil/trace.go:171","msg":"trace[499791751] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"114.701579ms","start":"2026-04-16T16:47:47.107842Z","end":"2026-04-16T16:47:47.222544Z","steps":["trace[499791751] 'process raft request' (duration: 108.964371ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:47.222792Z","caller":"traceutil/trace.go:171","msg":"trace[818971982] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"114.926884ms","start":"2026-04-16T16:47:47.107844Z","end":"2026-04-16T16:47:47.222771Z","steps":["trace[818971982] 'process raft request' (duration: 114.655918ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:47:47.22289Z","caller":"traceutil/trace.go:171","msg":"trace[1093804888] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8851; }","duration":"110.481756ms","start":"2026-04-16T16:47:47.112392Z","end":"2026-04-16T16:47:47.222874Z","steps":["trace[1093804888] 'read index received' (duration: 82.886502ms)","trace[1093804888] 'applied index is now lower than readState.Index' (duration: 27.593534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:47:47.223076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.067807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:47:47.223116Z","caller":"traceutil/trace.go:171","msg":"trace[1619468819] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8551; }","duration":"110.146639ms","start":"2026-04-16T16:47:47.112959Z","end":"2026-04-16T16:47:47.223106Z","steps":["trace[1619468819] 'agreement among raft nodes before linearized reading' (duration: 110.068527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:47:47.223396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.760423ms","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-16T16:47:47.223464Z","caller":"traceutil/trace.go:171","msg":"trace[867515144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8551; }","duration":"111.072052ms","start":"2026-04-16T16:47:47.112375Z","end":"2026-04-16T16:47:47.223447Z","steps":["trace[867515144] 'agreement among raft nodes before linearized reading' (duration: 110.60007ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:48:16.894884Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d7.wal"} {"level":"info","ts":"2026-04-16T16:49:51.975122Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T16:49:51.980514Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T16:49:51.980639Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T16:52:16.814869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8295} {"level":"info","ts":"2026-04-16T16:52:16.90369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8295,"took":"84.519864ms","hash":1202471984,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":24854528,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T16:52:16.903852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1202471984,"revision":8295,"compact-revision":6119} {"level":"info","ts":"2026-04-16T16:57:16.827567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10688} {"level":"info","ts":"2026-04-16T16:57:16.904069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10688,"took":"74.86003ms","hash":3799293910,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":20287488,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-16T16:57:16.904151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3799293910,"revision":10688,"compact-revision":8295}