{"level":"warn","ts":"2026-04-21T10:12:02.081854Z","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-21T10:12:02.081926Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-21T10:12:02.081986Z","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-21T10:12:02.081996Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-21T10:12:02.082019Z","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-21T10:12:02.082697Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-04-21T10:12:02.082777Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-21T10:12:02.086439Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.343392ms"} {"level":"info","ts":"2026-04-21T10:12:02.094774Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-04-21T10:12:02.094849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T10:12:02.094873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-04-21T10:12:02.094887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T10:12:02.094896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-04-21T10:12:02.094933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-04-21T10:12:02.102367Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T10:12:02.105416Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T10:12:02.106699Z","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-21T10:12:02.109003Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T10:12:02.109236Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T10:12:02.109524Z","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-21T10:12:02.10974Z","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-21T10:12:02.109812Z","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-21T10:12:02.111222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-04-21T10:12:02.111806Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-21T10:12:02.114308Z","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-21T10:12:02.114484Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-21T10:12:02.114552Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-21T10:12:02.114727Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T10:12:02.114861Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T10:12:02.495682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T10:12:02.495742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T10:12:02.495788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-04-21T10:12:02.495814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-04-21T10:12:02.495831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-21T10:12:02.495849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-04-21T10:12:02.495867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-21T10:12:02.503745Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T10:12:02.503921Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T10:12:02.50429Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T10:12:02.504555Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T10:12:02.504632Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T10:12:02.504711Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T10:12:02.504777Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T10:12:02.504884Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T10:12:02.504927Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T10:12:02.508473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T10:12:02.508479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-04-21T10:12:32.699646Z","caller":"traceutil/trace.go:171","msg":"trace[1894850584] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"273.870192ms","start":"2026-04-21T10:12:32.425734Z","end":"2026-04-21T10:12:32.699605Z","steps":["trace[1894850584] 'process raft request' (duration: 273.566975ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728316Z","caller":"traceutil/trace.go:171","msg":"trace[1962918184] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"243.797113ms","start":"2026-04-21T10:12:32.484473Z","end":"2026-04-21T10:12:32.72827Z","steps":["trace[1962918184] 'process raft request' (duration: 243.540488ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728369Z","caller":"traceutil/trace.go:171","msg":"trace[982596635] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"238.847505ms","start":"2026-04-21T10:12:32.489479Z","end":"2026-04-21T10:12:32.728326Z","steps":["trace[982596635] 'process raft request' (duration: 238.690511ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728552Z","caller":"traceutil/trace.go:171","msg":"trace[267121849] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"228.594101ms","start":"2026-04-21T10:12:32.499943Z","end":"2026-04-21T10:12:32.728537Z","steps":["trace[267121849] 'process raft request' (duration: 228.376616ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728561Z","caller":"traceutil/trace.go:171","msg":"trace[613998293] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"229.703925ms","start":"2026-04-21T10:12:32.498793Z","end":"2026-04-21T10:12:32.728497Z","steps":["trace[613998293] 'process raft request' (duration: 229.448409ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728561Z","caller":"traceutil/trace.go:171","msg":"trace[415759144] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"238.925298ms","start":"2026-04-21T10:12:32.489561Z","end":"2026-04-21T10:12:32.728487Z","steps":["trace[415759144] 'process raft request' (duration: 238.641951ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728614Z","caller":"traceutil/trace.go:171","msg":"trace[308050404] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"227.963258ms","start":"2026-04-21T10:12:32.500637Z","end":"2026-04-21T10:12:32.7286Z","steps":["trace[308050404] 'process raft request' (duration: 227.721551ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728644Z","caller":"traceutil/trace.go:171","msg":"trace[1175600194] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"228.792336ms","start":"2026-04-21T10:12:32.499843Z","end":"2026-04-21T10:12:32.728635Z","steps":["trace[1175600194] 'process raft request' (duration: 228.434678ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728773Z","caller":"traceutil/trace.go:171","msg":"trace[1572967419] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"224.961362ms","start":"2026-04-21T10:12:32.503766Z","end":"2026-04-21T10:12:32.728727Z","steps":["trace[1572967419] 'process raft request' (duration: 224.630664ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728868Z","caller":"traceutil/trace.go:171","msg":"trace[1794099494] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"216.650271ms","start":"2026-04-21T10:12:32.512179Z","end":"2026-04-21T10:12:32.728829Z","steps":["trace[1794099494] 'process raft request' (duration: 216.352464ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.728894Z","caller":"traceutil/trace.go:171","msg":"trace[360339728] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"194.061776ms","start":"2026-04-21T10:12:32.534815Z","end":"2026-04-21T10:12:32.728877Z","steps":["trace[360339728] 'process raft request' (duration: 193.928633ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:32.729063Z","caller":"traceutil/trace.go:171","msg":"trace[333790366] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"172.117336ms","start":"2026-04-21T10:12:32.556882Z","end":"2026-04-21T10:12:32.728999Z","steps":["trace[333790366] 'process raft request' (duration: 171.942562ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:38.908363Z","caller":"traceutil/trace.go:171","msg":"trace[1726112277] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"109.151658ms","start":"2026-04-21T10:12:38.799184Z","end":"2026-04-21T10:12:38.908336Z","steps":["trace[1726112277] 'read index received' (duration: 109.011994ms)","trace[1726112277] 'applied index is now lower than readState.Index' (duration: 138.494µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:12:38.9086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.389374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T10:12:38.908488Z","caller":"traceutil/trace.go:171","msg":"trace[546306111] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"167.881423ms","start":"2026-04-21T10:12:38.740571Z","end":"2026-04-21T10:12:38.908453Z","steps":["trace[546306111] 'process raft request' (duration: 167.598726ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:38.908705Z","caller":"traceutil/trace.go:171","msg":"trace[1758616102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"109.574027ms","start":"2026-04-21T10:12:38.799118Z","end":"2026-04-21T10:12:38.908692Z","steps":["trace[1758616102] 'agreement among raft nodes before linearized reading' (duration: 109.329052ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:39.183952Z","caller":"traceutil/trace.go:171","msg":"trace[2039670740] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"149.8808ms","start":"2026-04-21T10:12:39.03404Z","end":"2026-04-21T10:12:39.18392Z","steps":["trace[2039670740] 'process raft request' (duration: 149.050662ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:12:39.666482Z","caller":"traceutil/trace.go:171","msg":"trace[819735542] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"228.355096ms","start":"2026-04-21T10:12:39.438091Z","end":"2026-04-21T10:12:39.666447Z","steps":["trace[819735542] 'read index received' (duration: 228.196862ms)","trace[819735542] 'applied index is now lower than readState.Index' (duration: 155.764µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:12:39.666538Z","caller":"traceutil/trace.go:171","msg":"trace[390607712] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"424.903547ms","start":"2026-04-21T10:12:39.241592Z","end":"2026-04-21T10:12:39.666496Z","steps":["trace[390607712] 'process raft request' (duration: 424.670722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:12:39.666772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.663233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dsnfz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-21T10:12:39.666879Z","caller":"traceutil/trace.go:171","msg":"trace[331785938] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dsnfz; range_end:; response_count:1; response_revision:623; }","duration":"228.820247ms","start":"2026-04-21T10:12:39.43804Z","end":"2026-04-21T10:12:39.666861Z","steps":["trace[331785938] 'agreement among raft nodes before linearized reading' (duration: 228.539131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:12:39.667224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:12:39.24155Z","time spent":"425.103171ms","remote":"127.0.0.1:44700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T10:13:03.370297Z","caller":"traceutil/trace.go:171","msg":"trace[588410155] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"234.967321ms","start":"2026-04-21T10:13:03.135293Z","end":"2026-04-21T10:13:03.37026Z","steps":["trace[588410155] 'process raft request' (duration: 152.737392ms)","trace[588410155] 'compare' (duration: 82.089356ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:13:34.653171Z","caller":"traceutil/trace.go:171","msg":"trace[1520017092] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"200.385666ms","start":"2026-04-21T10:13:34.452723Z","end":"2026-04-21T10:13:34.653109Z","steps":["trace[1520017092] 'process raft request' (duration: 181.314979ms)","trace[1520017092] 'compare' (duration: 18.905864ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:14:20.280336Z","caller":"traceutil/trace.go:171","msg":"trace[143594647] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"275.942289ms","start":"2026-04-21T10:14:20.004375Z","end":"2026-04-21T10:14:20.280317Z","steps":["trace[143594647] 'read index received' (duration: 275.49666ms)","trace[143594647] 'applied index is now lower than readState.Index' (duration: 445.009µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:14:20.280393Z","caller":"traceutil/trace.go:171","msg":"trace[857427462] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"333.941939ms","start":"2026-04-21T10:14:19.946406Z","end":"2026-04-21T10:14:20.280348Z","steps":["trace[857427462] 'process raft request' (duration: 333.328576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:14:20.280448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.067202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e7cb9cd7-bb8c-40cf-a61c-a7e082ea269c\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-21T10:14:20.28047Z","caller":"traceutil/trace.go:171","msg":"trace[1471366252] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e7cb9cd7-bb8c-40cf-a61c-a7e082ea269c; range_end:; response_count:1; response_revision:1875; }","duration":"276.139533ms","start":"2026-04-21T10:14:20.004324Z","end":"2026-04-21T10:14:20.280464Z","steps":["trace[1471366252] 'agreement among raft nodes before linearized reading' (duration: 276.085072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:14:20.28078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:14:19.946379Z","time spent":"334.085892ms","remote":"127.0.0.1:46094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:14:20.305945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.397159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T10:14:20.306008Z","caller":"traceutil/trace.go:171","msg":"trace[97491591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1876; }","duration":"212.501901ms","start":"2026-04-21T10:14:20.093491Z","end":"2026-04-21T10:14:20.305993Z","steps":["trace[97491591] 'agreement among raft nodes before linearized reading' (duration: 212.397209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:14:31.855306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.322061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T10:14:31.855407Z","caller":"traceutil/trace.go:171","msg":"trace[1795819750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1977; }","duration":"243.473845ms","start":"2026-04-21T10:14:31.611912Z","end":"2026-04-21T10:14:31.855386Z","steps":["trace[1795819750] 'range keys from in-memory index tree' (duration: 243.095197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:14:31.855726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.423168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-21T10:14:31.855904Z","caller":"traceutil/trace.go:171","msg":"trace[1563198382] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1977; }","duration":"295.754935ms","start":"2026-04-21T10:14:31.560108Z","end":"2026-04-21T10:14:31.855863Z","steps":["trace[1563198382] 'range keys from in-memory index tree' (duration: 295.163822ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:15:11.084396Z","caller":"traceutil/trace.go:171","msg":"trace[412031103] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"316.439467ms","start":"2026-04-21T10:15:10.767905Z","end":"2026-04-21T10:15:11.084344Z","steps":["trace[412031103] 'process raft request' (duration: 316.202041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:15:11.084627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:15:10.767874Z","time spent":"316.638621ms","remote":"127.0.0.1:46078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T10:15:11.112323Z","caller":"traceutil/trace.go:171","msg":"trace[694385956] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2289; }","duration":"124.819684ms","start":"2026-04-21T10:15:10.987479Z","end":"2026-04-21T10:15:11.112299Z","steps":["trace[694385956] 'read index received' (duration: 96.621288ms)","trace[694385956] 'applied index is now lower than readState.Index' (duration: 28.197416ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:15:11.112368Z","caller":"traceutil/trace.go:171","msg":"trace[546388184] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"157.356135ms","start":"2026-04-21T10:15:10.954975Z","end":"2026-04-21T10:15:11.112331Z","steps":["trace[546388184] 'process raft request' (duration: 157.120979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:15:11.11269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.191573ms","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-21T10:15:11.113078Z","caller":"traceutil/trace.go:171","msg":"trace[1137100550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2205; }","duration":"125.447019ms","start":"2026-04-21T10:15:10.987412Z","end":"2026-04-21T10:15:11.112859Z","steps":["trace[1137100550] 'agreement among raft nodes before linearized reading' (duration: 125.02609ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:15:58.969332Z","caller":"traceutil/trace.go:171","msg":"trace[1831911099] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"169.829445ms","start":"2026-04-21T10:15:58.799483Z","end":"2026-04-21T10:15:58.969313Z","steps":["trace[1831911099] 'process raft request' (duration: 169.700312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:16:33.439984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.307744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-21T10:16:33.440136Z","caller":"traceutil/trace.go:171","msg":"trace[343301208] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2844; }","duration":"134.474557ms","start":"2026-04-21T10:16:33.305623Z","end":"2026-04-21T10:16:33.440098Z","steps":["trace[343301208] 'range keys from in-memory index tree' (duration: 134.078399ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:16:33.568348Z","caller":"traceutil/trace.go:171","msg":"trace[639762386] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"138.351562ms","start":"2026-04-21T10:16:33.429967Z","end":"2026-04-21T10:16:33.568319Z","steps":["trace[639762386] 'process raft request' (duration: 138.198829ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:16:33.579916Z","caller":"traceutil/trace.go:171","msg":"trace[105494929] linearizableReadLoop","detail":"{readStateIndex:2958; appliedIndex:2956; }","duration":"136.665795ms","start":"2026-04-21T10:16:33.443231Z","end":"2026-04-21T10:16:33.579897Z","steps":["trace[105494929] 'read index received' (duration: 124.948491ms)","trace[105494929] 'applied index is now lower than readState.Index' (duration: 11.716464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:16:33.580092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.847509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-04-21T10:16:33.580014Z","caller":"traceutil/trace.go:171","msg":"trace[1490447430] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"143.598975ms","start":"2026-04-21T10:16:33.436363Z","end":"2026-04-21T10:16:33.579962Z","steps":["trace[1490447430] 'process raft request' (duration: 143.432331ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:16:33.580138Z","caller":"traceutil/trace.go:171","msg":"trace[638389101] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2846; }","duration":"136.90732ms","start":"2026-04-21T10:16:33.443217Z","end":"2026-04-21T10:16:33.580124Z","steps":["trace[638389101] 'agreement among raft nodes before linearized reading' (duration: 136.770667ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:18:58.477825Z","caller":"traceutil/trace.go:171","msg":"trace[561343376] transaction","detail":"{read_only:false; response_revision:3813; number_of_response:1; }","duration":"119.314028ms","start":"2026-04-21T10:18:58.358484Z","end":"2026-04-21T10:18:58.477798Z","steps":["trace[561343376] 'process raft request' (duration: 119.150144ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:20:25.736493Z","caller":"traceutil/trace.go:171","msg":"trace[94377582] linearizableReadLoop","detail":"{readStateIndex:5226; appliedIndex:5225; }","duration":"115.25033ms","start":"2026-04-21T10:20:25.621216Z","end":"2026-04-21T10:20:25.736466Z","steps":["trace[94377582] 'read index received' (duration: 115.107937ms)","trace[94377582] 'applied index is now lower than readState.Index' (duration: 141.013µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:20:25.736798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.558187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-21T10:20:25.736819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.757019ms","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-21T10:20:25.73689Z","caller":"traceutil/trace.go:171","msg":"trace[1342095674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5033; }","duration":"115.682869ms","start":"2026-04-21T10:20:25.621184Z","end":"2026-04-21T10:20:25.736867Z","steps":["trace[1342095674] 'agreement among raft nodes before linearized reading' (duration: 115.427884ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:20:25.736882Z","caller":"traceutil/trace.go:171","msg":"trace[1999752203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5033; }","duration":"108.875731ms","start":"2026-04-21T10:20:25.627996Z","end":"2026-04-21T10:20:25.736871Z","steps":["trace[1999752203] 'agreement among raft nodes before linearized reading' (duration: 108.621646ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:20:25.931319Z","caller":"traceutil/trace.go:171","msg":"trace[1939654116] linearizableReadLoop","detail":"{readStateIndex:5229; appliedIndex:5226; }","duration":"137.508901ms","start":"2026-04-21T10:20:25.793782Z","end":"2026-04-21T10:20:25.931291Z","steps":["trace[1939654116] 'read index received' (duration: 13.702296ms)","trace[1939654116] 'applied index is now lower than readState.Index' (duration: 123.805305ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:20:25.931351Z","caller":"traceutil/trace.go:171","msg":"trace[1973267930] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"184.989217ms","start":"2026-04-21T10:20:25.746325Z","end":"2026-04-21T10:20:25.931314Z","steps":["trace[1973267930] 'process raft request' (duration: 184.883824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:20:25.931471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.675844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8582f9a-3a16-4caf-a47c-3addc465c35f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-21T10:20:25.931501Z","caller":"traceutil/trace.go:171","msg":"trace[1657969674] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f8582f9a-3a16-4caf-a47c-3addc465c35f; range_end:; response_count:1; response_revision:5036; }","duration":"137.769886ms","start":"2026-04-21T10:20:25.793722Z","end":"2026-04-21T10:20:25.931492Z","steps":["trace[1657969674] 'agreement among raft nodes before linearized reading' (duration: 137.663114ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:20:25.931507Z","caller":"traceutil/trace.go:171","msg":"trace[736695272] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"187.987332ms","start":"2026-04-21T10:20:25.743469Z","end":"2026-04-21T10:20:25.931456Z","steps":["trace[736695272] 'process raft request' (duration: 182.598945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:20:25.931559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.141989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-21T10:20:25.931687Z","caller":"traceutil/trace.go:171","msg":"trace[1668904355] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5036; }","duration":"122.320253ms","start":"2026-04-21T10:20:25.809344Z","end":"2026-04-21T10:20:25.931665Z","steps":["trace[1668904355] 'agreement among raft nodes before linearized reading' (duration: 122.121309ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:20:36.140288Z","caller":"traceutil/trace.go:171","msg":"trace[1364168874] linearizableReadLoop","detail":"{readStateIndex:5439; appliedIndex:5438; }","duration":"169.689036ms","start":"2026-04-21T10:20:35.970565Z","end":"2026-04-21T10:20:36.140254Z","steps":["trace[1364168874] 'read index received' (duration: 169.326888ms)","trace[1364168874] 'applied index is now lower than readState.Index' (duration: 361.058µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:20:36.140367Z","caller":"traceutil/trace.go:171","msg":"trace[1048290890] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"220.053724ms","start":"2026-04-21T10:20:35.920271Z","end":"2026-04-21T10:20:36.140325Z","steps":["trace[1048290890] 'process raft request' (duration: 219.773718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:20:36.14061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.473899ms","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-21T10:20:36.140671Z","caller":"traceutil/trace.go:171","msg":"trace[204880645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5240; }","duration":"161.57228ms","start":"2026-04-21T10:20:35.979082Z","end":"2026-04-21T10:20:36.140654Z","steps":["trace[204880645] 'agreement among raft nodes before linearized reading' (duration: 161.351416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:20:36.140697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.096824ms","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-21T10:20:36.140883Z","caller":"traceutil/trace.go:171","msg":"trace[1626924448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5240; }","duration":"170.35416ms","start":"2026-04-21T10:20:35.9705Z","end":"2026-04-21T10:20:36.140854Z","steps":["trace[1626924448] 'agreement among raft nodes before linearized reading' (duration: 169.943702ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:20:39.376997Z","caller":"traceutil/trace.go:171","msg":"trace[454004599] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"103.131899ms","start":"2026-04-21T10:20:39.273833Z","end":"2026-04-21T10:20:39.376965Z","steps":["trace[454004599] 'process raft request' (duration: 86.797896ms)","trace[454004599] 'compare' (duration: 16.086986ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:20:43.490553Z","caller":"traceutil/trace.go:171","msg":"trace[1226714183] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"102.151758ms","start":"2026-04-21T10:20:43.388364Z","end":"2026-04-21T10:20:43.490516Z","steps":["trace[1226714183] 'process raft request' (duration: 56.385459ms)","trace[1226714183] 'compare' (duration: 45.599045ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:22:02.542468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3006} {"level":"info","ts":"2026-04-21T10:22:02.628061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3006,"took":"84.051458ms","hash":3123592018,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":31805440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-21T10:22:02.628161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3123592018,"revision":3006,"compact-revision":-1} {"level":"info","ts":"2026-04-21T10:26:51.937376Z","caller":"traceutil/trace.go:171","msg":"trace[755677177] transaction","detail":"{read_only:false; response_revision:8246; number_of_response:1; }","duration":"140.541536ms","start":"2026-04-21T10:26:51.796808Z","end":"2026-04-21T10:26:51.937349Z","steps":["trace[755677177] 'process raft request' (duration: 140.370223ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:26:51.938486Z","caller":"traceutil/trace.go:171","msg":"trace[339722562] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"137.566242ms","start":"2026-04-21T10:26:51.800882Z","end":"2026-04-21T10:26:51.938448Z","steps":["trace[339722562] 'process raft request' (duration: 137.350618ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:27:02.61268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6132} {"level":"info","ts":"2026-04-21T10:27:02.925912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6132,"took":"310.288827ms","hash":2470057278,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":23367680,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-21T10:27:02.926018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2470057278,"revision":6132,"compact-revision":3006} {"level":"info","ts":"2026-04-21T10:27:03.538489Z","caller":"traceutil/trace.go:171","msg":"trace[198579974] linearizableReadLoop","detail":"{readStateIndex:8606; appliedIndex:8605; }","duration":"172.46563ms","start":"2026-04-21T10:27:03.366001Z","end":"2026-04-21T10:27:03.538467Z","steps":["trace[198579974] 'read index received' (duration: 172.204814ms)","trace[198579974] 'applied index is now lower than readState.Index' (duration: 260.006µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:27:03.538593Z","caller":"traceutil/trace.go:171","msg":"trace[421810620] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"241.334563ms","start":"2026-04-21T10:27:03.297225Z","end":"2026-04-21T10:27:03.538559Z","steps":["trace[421810620] 'process raft request' (duration: 241.089068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:27:03.538777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.742765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-21T10:27:03.538846Z","caller":"traceutil/trace.go:171","msg":"trace[1744601371] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:8311; }","duration":"172.875078ms","start":"2026-04-21T10:27:03.365953Z","end":"2026-04-21T10:27:03.538828Z","steps":["trace[1744601371] 'agreement among raft nodes before linearized reading' (duration: 172.608303ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:27:04.182905Z","caller":"traceutil/trace.go:171","msg":"trace[1390225813] transaction","detail":"{read_only:false; response_revision:8312; number_of_response:1; }","duration":"202.111284ms","start":"2026-04-21T10:27:03.98076Z","end":"2026-04-21T10:27:04.182871Z","steps":["trace[1390225813] 'process raft request' (duration: 201.888749ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:27:04.196065Z","caller":"traceutil/trace.go:171","msg":"trace[820000406] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"213.388075ms","start":"2026-04-21T10:27:03.982644Z","end":"2026-04-21T10:27:04.196032Z","steps":["trace[820000406] 'process raft request' (duration: 213.17773ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:27:04.971484Z","caller":"traceutil/trace.go:171","msg":"trace[1488958327] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"147.054256ms","start":"2026-04-21T10:27:04.824384Z","end":"2026-04-21T10:27:04.971438Z","steps":["trace[1488958327] 'process raft request' (duration: 146.651727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:27:35.520399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.212016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-21T10:27:35.520502Z","caller":"traceutil/trace.go:171","msg":"trace[318432759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8534; }","duration":"147.37059ms","start":"2026-04-21T10:27:35.373112Z","end":"2026-04-21T10:27:35.520483Z","steps":["trace[318432759] 'range keys from in-memory index tree' (duration: 146.994041ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:27:35.633699Z","caller":"traceutil/trace.go:171","msg":"trace[1686038803] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"109.057157ms","start":"2026-04-21T10:27:35.524606Z","end":"2026-04-21T10:27:35.633663Z","steps":["trace[1686038803] 'process raft request' (duration: 108.899214ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:27:44.482923Z","caller":"traceutil/trace.go:171","msg":"trace[418926375] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"156.430973ms","start":"2026-04-21T10:27:44.326472Z","end":"2026-04-21T10:27:44.482903Z","steps":["trace[418926375] 'process raft request' (duration: 156.300691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:27:44.713595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.550044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T10:27:44.713858Z","caller":"traceutil/trace.go:171","msg":"trace[1524958068] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"257.381567ms","start":"2026-04-21T10:27:44.456431Z","end":"2026-04-21T10:27:44.713812Z","steps":["trace[1524958068] 'process raft request' (duration: 122.319062ms)","trace[1524958068] 'compare' (duration: 134.244158ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:27:44.7139Z","caller":"traceutil/trace.go:171","msg":"trace[891454711] linearizableReadLoop","detail":"{readStateIndex:8932; appliedIndex:8930; }","duration":"231.000262ms","start":"2026-04-21T10:27:44.482878Z","end":"2026-04-21T10:27:44.713878Z","steps":["trace[891454711] 'read index received' (duration: 95.893955ms)","trace[891454711] 'applied index is now lower than readState.Index' (duration: 135.104957ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:27:44.713953Z","caller":"traceutil/trace.go:171","msg":"trace[29981772] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"249.0516ms","start":"2026-04-21T10:27:44.464873Z","end":"2026-04-21T10:27:44.713924Z","steps":["trace[29981772] 'process raft request' (duration: 248.849635ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:27:44.714023Z","caller":"traceutil/trace.go:171","msg":"trace[585766443] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"227.766012ms","start":"2026-04-21T10:27:44.48624Z","end":"2026-04-21T10:27:44.714006Z","steps":["trace[585766443] 'process raft request' (duration: 227.589739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:27:44.714191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.470211ms","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":"warn","ts":"2026-04-21T10:27:44.714216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.097443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mw5hs\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-04-21T10:27:44.714256Z","caller":"traceutil/trace.go:171","msg":"trace[107251371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8625; }","duration":"286.595353ms","start":"2026-04-21T10:27:44.427642Z","end":"2026-04-21T10:27:44.714238Z","steps":["trace[107251371] 'agreement among raft nodes before linearized reading' (duration: 286.325447ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:27:44.71432Z","caller":"traceutil/trace.go:171","msg":"trace[2036136764] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mw5hs; range_end:; response_count:1; response_revision:8625; }","duration":"258.244945ms","start":"2026-04-21T10:27:44.456048Z","end":"2026-04-21T10:27:44.714293Z","steps":["trace[2036136764] 'agreement among raft nodes before linearized reading' (duration: 257.838757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:27:44.714737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.395848ms","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-21T10:27:44.714816Z","caller":"traceutil/trace.go:171","msg":"trace[292537932] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8625; }","duration":"306.974841ms","start":"2026-04-21T10:27:44.40782Z","end":"2026-04-21T10:27:44.714795Z","steps":["trace[292537932] 'agreement among raft nodes before linearized reading' (duration: 306.131352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:27:44.714896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:27:44.407807Z","time spent":"307.078163ms","remote":"127.0.0.1:46094","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-21T10:27:45.322111Z","caller":"traceutil/trace.go:171","msg":"trace[1591963289] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"104.01802ms","start":"2026-04-21T10:27:45.218067Z","end":"2026-04-21T10:27:45.322085Z","steps":["trace[1591963289] 'process raft request' (duration: 78.879601ms)","trace[1591963289] 'compare' (duration: 25.035377ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:27:45.364985Z","caller":"traceutil/trace.go:171","msg":"trace[323647777] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"145.742434ms","start":"2026-04-21T10:27:45.21922Z","end":"2026-04-21T10:27:45.364962Z","steps":["trace[323647777] 'process raft request' (duration: 145.57049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:27:45.653613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.980482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-21T10:27:45.653703Z","caller":"traceutil/trace.go:171","msg":"trace[1900335146] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:8633; }","duration":"231.086334ms","start":"2026-04-21T10:27:45.422595Z","end":"2026-04-21T10:27:45.653681Z","steps":["trace[1900335146] 'range keys from in-memory index tree' (duration: 230.883459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:27:45.653963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.832239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T10:27:45.654017Z","caller":"traceutil/trace.go:171","msg":"trace[491367709] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:8633; }","duration":"217.913752ms","start":"2026-04-21T10:27:45.43609Z","end":"2026-04-21T10:27:45.654003Z","steps":["trace[491367709] 'count revisions from in-memory index tree' (duration: 217.770958ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:28:28.931432Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002487.wal"} {"level":"info","ts":"2026-04-21T10:28:59.321268Z","caller":"traceutil/trace.go:171","msg":"trace[755567277] linearizableReadLoop","detail":"{readStateIndex:9696; appliedIndex:9695; }","duration":"237.405229ms","start":"2026-04-21T10:28:59.083831Z","end":"2026-04-21T10:28:59.321237Z","steps":["trace[755567277] 'read index received' (duration: 216.105992ms)","trace[755567277] 'applied index is now lower than readState.Index' (duration: 21.296787ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:28:59.321308Z","caller":"traceutil/trace.go:171","msg":"trace[118302542] transaction","detail":"{read_only:false; response_revision:9367; number_of_response:1; }","duration":"237.806987ms","start":"2026-04-21T10:28:59.083454Z","end":"2026-04-21T10:28:59.321261Z","steps":["trace[118302542] 'process raft request' (duration: 216.51377ms)","trace[118302542] 'compare' (duration: 21.140674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:28:59.321497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.644755ms","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-21T10:28:59.321669Z","caller":"traceutil/trace.go:171","msg":"trace[352619876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9367; }","duration":"237.848448ms","start":"2026-04-21T10:28:59.083797Z","end":"2026-04-21T10:28:59.321645Z","steps":["trace[352619876] 'agreement among raft nodes before linearized reading' (duration: 237.568602ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:28:59.460403Z","caller":"traceutil/trace.go:171","msg":"trace[381339634] transaction","detail":"{read_only:false; response_revision:9368; number_of_response:1; }","duration":"135.267669ms","start":"2026-04-21T10:28:59.325087Z","end":"2026-04-21T10:28:59.460355Z","steps":["trace[381339634] 'process raft request' (duration: 117.594181ms)","trace[381339634] 'compare' (duration: 17.443084ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:28:59.46042Z","caller":"traceutil/trace.go:171","msg":"trace[298587086] linearizableReadLoop","detail":"{readStateIndex:9697; appliedIndex:9696; }","duration":"107.30214ms","start":"2026-04-21T10:28:59.353094Z","end":"2026-04-21T10:28:59.460396Z","steps":["trace[298587086] 'read index received' (duration: 89.470958ms)","trace[298587086] 'applied index is now lower than readState.Index' (duration: 17.829082ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:28:59.460638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.08886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T10:28:59.460684Z","caller":"traceutil/trace.go:171","msg":"trace[553479915] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:9369; }","duration":"103.181152ms","start":"2026-04-21T10:28:59.357486Z","end":"2026-04-21T10:28:59.460667Z","steps":["trace[553479915] 'agreement among raft nodes before linearized reading' (duration: 103.08008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:28:59.460756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.635688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gpj9l\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-21T10:28:59.460844Z","caller":"traceutil/trace.go:171","msg":"trace[1450642225] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gpj9l; range_end:; response_count:1; response_revision:9369; }","duration":"107.775171ms","start":"2026-04-21T10:28:59.353037Z","end":"2026-04-21T10:28:59.460812Z","steps":["trace[1450642225] 'agreement among raft nodes before linearized reading' (duration: 107.507115ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:29:37.915905Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1686e9b1ea717edf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-21T10:29:37.923425Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-21T10:29:37.923514Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-21T10:32:02.619856Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8306} {"level":"info","ts":"2026-04-21T10:32:02.700597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8306,"took":"78.399488ms","hash":3829215667,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":25030656,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-21T10:32:02.700669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3829215667,"revision":8306,"compact-revision":6132} {"level":"info","ts":"2026-04-21T10:37:02.627474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10709} {"level":"info","ts":"2026-04-21T10:37:02.705954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10709,"took":"73.403141ms","hash":842963481,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":20647936,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-21T10:37:02.706029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":842963481,"revision":10709,"compact-revision":8306}