{"level":"warn","ts":"2026-05-19T05:12:10.321803Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T05:12:10.321881Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-05-19T05:12:10.321943Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T05:12:10.321956Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-19T05:12:10.321972Z","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-05-19T05:12:10.322614Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-05-19T05:12:10.322707Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-05-19T05:12:10.327339Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.431612ms"} {"level":"info","ts":"2026-05-19T05:12:10.333257Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-05-19T05:12:10.33333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T05:12:10.333352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-05-19T05:12:10.333362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T05:12:10.33337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-05-19T05:12:10.333407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-05-19T05:12:10.341997Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T05:12:10.343689Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T05:12:10.344693Z","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-05-19T05:12:10.347102Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T05:12:10.347236Z","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-05-19T05:12:10.347292Z","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-05-19T05:12:10.347302Z","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-05-19T05:12:10.34724Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T05:12:10.350503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-05-19T05:12:10.352507Z","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-05-19T05:12:10.352556Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-19T05:12:10.352651Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-19T05:12:10.352674Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-19T05:12:10.352722Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T05:12:10.353196Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T05:12:10.735065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T05:12:10.735151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T05:12:10.73522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-05-19T05:12:10.73525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-05-19T05:12:10.735262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-19T05:12:10.735286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-05-19T05:12:10.735308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-19T05:12:10.739979Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T05:12:10.740029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T05:12:10.740623Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:12:10.740948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T05:12:10.74224Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T05:12:10.742275Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T05:12:10.742705Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:12:10.742795Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:12:10.742829Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:12:10.745129Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T05:12:10.745646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"warn","ts":"2026-05-19T05:12:13.95939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.356376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:12:13.959514Z","caller":"traceutil/trace.go:171","msg":"trace[977829664] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:208; }","duration":"123.714224ms","start":"2026-05-19T05:12:13.835782Z","end":"2026-05-19T05:12:13.959497Z","steps":["trace[977829664] 'read index received' (duration: 35.511µs)","trace[977829664] 'applied index is now lower than readState.Index' (duration: 123.677463ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:12:13.959619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.838495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:12:13.959652Z","caller":"traceutil/trace.go:171","msg":"trace[1802868703] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:204; }","duration":"123.877615ms","start":"2026-05-19T05:12:13.835766Z","end":"2026-05-19T05:12:13.959643Z","steps":["trace[1802868703] 'agreement among raft nodes before linearized reading' (duration: 123.788765ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:12:13.959736Z","caller":"traceutil/trace.go:171","msg":"trace[1776532912] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"329.331757ms","start":"2026-05-19T05:12:13.630367Z","end":"2026-05-19T05:12:13.959698Z","steps":["trace[1776532912] 'process raft request' (duration: 120.159697ms)","trace[1776532912] 'compare' (duration: 208.210795ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:12:13.959879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:12:13.630342Z","time spent":"329.468448ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T05:12:14.272237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.584665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:12:14.272508Z","caller":"traceutil/trace.go:171","msg":"trace[1986279958] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"307.193629ms","start":"2026-05-19T05:12:13.965269Z","end":"2026-05-19T05:12:14.272462Z","steps":["trace[1986279958] 'process raft request' (duration: 148.265372ms)","trace[1986279958] 'compare' (duration: 158.396785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:12:14.272693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:12:13.965247Z","time spent":"307.356439ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T05:12:14.273158Z","caller":"traceutil/trace.go:171","msg":"trace[1280812841] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"306.165816ms","start":"2026-05-19T05:12:13.966969Z","end":"2026-05-19T05:12:14.273135Z","steps":["trace[1280812841] 'process raft request' (duration: 305.478842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:12:14.273244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:12:13.966927Z","time spent":"306.288626ms","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T05:12:45.966496Z","caller":"traceutil/trace.go:171","msg":"trace[529939104] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"164.324378ms","start":"2026-05-19T05:12:45.802139Z","end":"2026-05-19T05:12:45.966464Z","steps":["trace[529939104] 'read index received' (duration: 7.389786ms)","trace[529939104] 'applied index is now lower than readState.Index' (duration: 156.933302ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:12:45.974341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.170436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T05:12:45.974449Z","caller":"traceutil/trace.go:171","msg":"trace[1386239328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"172.314006ms","start":"2026-05-19T05:12:45.802102Z","end":"2026-05-19T05:12:45.974416Z","steps":["trace[1386239328] 'agreement among raft nodes before linearized reading' (duration: 164.465708ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:12:45.975272Z","caller":"traceutil/trace.go:171","msg":"trace[821786213] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"228.451553ms","start":"2026-05-19T05:12:45.746804Z","end":"2026-05-19T05:12:45.975255Z","steps":["trace[821786213] 'process raft request' (duration: 219.06289ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:12:47.256069Z","caller":"traceutil/trace.go:171","msg":"trace[1823379692] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"208.260745ms","start":"2026-05-19T05:12:47.047785Z","end":"2026-05-19T05:12:47.256046Z","steps":["trace[1823379692] 'process raft request' (duration: 206.93252ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:12:47.256544Z","caller":"traceutil/trace.go:171","msg":"trace[319017351] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"204.180741ms","start":"2026-05-19T05:12:47.05176Z","end":"2026-05-19T05:12:47.255941Z","steps":["trace[319017351] 'read index received' (duration: 196.026682ms)","trace[319017351] 'applied index is now lower than readState.Index' (duration: 8.153069ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:12:47.256516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.710202ms","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-05-19T05:12:47.256637Z","caller":"traceutil/trace.go:171","msg":"trace[2000106894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"204.909443ms","start":"2026-05-19T05:12:47.051713Z","end":"2026-05-19T05:12:47.256622Z","steps":["trace[2000106894] 'agreement among raft nodes before linearized reading' (duration: 204.424501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:12:47.45277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.246643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:12:47.452871Z","caller":"traceutil/trace.go:171","msg":"trace[1725461978] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"196.261042ms","start":"2026-05-19T05:12:47.256589Z","end":"2026-05-19T05:12:47.45285Z","steps":["trace[1725461978] 'read index received' (duration: 45.729079ms)","trace[1725461978] 'applied index is now lower than readState.Index' (duration: 150.530823ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:12:47.452925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.255391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:12:47.45295Z","caller":"traceutil/trace.go:171","msg":"trace[270867260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"230.279981ms","start":"2026-05-19T05:12:47.222661Z","end":"2026-05-19T05:12:47.452941Z","steps":["trace[270867260] 'agreement among raft nodes before linearized reading' (duration: 230.2357ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:12:47.453174Z","caller":"traceutil/trace.go:171","msg":"trace[2111773893] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"230.772922ms","start":"2026-05-19T05:12:47.22239Z","end":"2026-05-19T05:12:47.453163Z","steps":["trace[2111773893] 'process raft request' (duration: 79.918447ms)","trace[2111773893] 'compare' (duration: 150.146893ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:12:47.467952Z","caller":"traceutil/trace.go:171","msg":"trace[748503156] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"206.207766ms","start":"2026-05-19T05:12:47.261702Z","end":"2026-05-19T05:12:47.46791Z","steps":["trace[748503156] 'process raft request' (duration: 206.062146ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:12:47.475909Z","caller":"traceutil/trace.go:171","msg":"trace[707041708] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"152.44157ms","start":"2026-05-19T05:12:47.323413Z","end":"2026-05-19T05:12:47.475854Z","steps":["trace[707041708] 'process raft request' (duration: 152.34355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:12:48.20867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.559044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mx4qb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T05:12:48.208833Z","caller":"traceutil/trace.go:171","msg":"trace[1757089999] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mx4qb; range_end:; response_count:1; response_revision:620; }","duration":"651.760954ms","start":"2026-05-19T05:12:47.55705Z","end":"2026-05-19T05:12:48.208811Z","steps":["trace[1757089999] 'range keys from in-memory index tree' (duration: 651.331003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:12:48.208934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:12:47.557032Z","time spent":"651.887945ms","remote":"127.0.0.1:51444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-mx4qb\" "} {"level":"warn","ts":"2026-05-19T05:12:48.208808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.127391ms","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-05-19T05:12:48.2092Z","caller":"traceutil/trace.go:171","msg":"trace[932957197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"395.596023ms","start":"2026-05-19T05:12:47.813578Z","end":"2026-05-19T05:12:48.209174Z","steps":["trace[932957197] 'range keys from in-memory index tree' (duration: 394.885441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:12:48.209258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:12:47.813548Z","time spent":"395.693873ms","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T05:12:48.209352Z","caller":"traceutil/trace.go:171","msg":"trace[1387043955] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"220.024582ms","start":"2026-05-19T05:12:47.989294Z","end":"2026-05-19T05:12:48.209318Z","steps":["trace[1387043955] 'read index received' (duration: 194.016682ms)","trace[1387043955] 'applied index is now lower than readState.Index' (duration: 26.00645ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:12:48.20953Z","caller":"traceutil/trace.go:171","msg":"trace[809339540] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"325.344308ms","start":"2026-05-19T05:12:47.884168Z","end":"2026-05-19T05:12:48.209513Z","steps":["trace[809339540] 'process raft request' (duration: 299.313078ms)","trace[809339540] 'compare' (duration: 25.309647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:12:48.209671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.355763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T05:12:48.209719Z","caller":"traceutil/trace.go:171","msg":"trace[1168957966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"220.492854ms","start":"2026-05-19T05:12:47.989213Z","end":"2026-05-19T05:12:48.209706Z","steps":["trace[1168957966] 'agreement among raft nodes before linearized reading' (duration: 220.231183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:12:48.20983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:12:47.88413Z","time spent":"325.498238ms","remote":"127.0.0.1:51374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:14:21.201796Z","caller":"traceutil/trace.go:171","msg":"trace[1420571755] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"116.484777ms","start":"2026-05-19T05:14:21.085287Z","end":"2026-05-19T05:14:21.201772Z","steps":["trace[1420571755] 'process raft request' (duration: 116.320784ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:14:21.500154Z","caller":"traceutil/trace.go:171","msg":"trace[818181016] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"100.862887ms","start":"2026-05-19T05:14:21.399267Z","end":"2026-05-19T05:14:21.50013Z","steps":["trace[818181016] 'read index received' (duration: 100.814546ms)","trace[818181016] 'applied index is now lower than readState.Index' (duration: 47.231µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:14:21.500282Z","caller":"traceutil/trace.go:171","msg":"trace[2060419871] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"276.732987ms","start":"2026-05-19T05:14:21.223487Z","end":"2026-05-19T05:14:21.50022Z","steps":["trace[2060419871] 'process raft request' (duration: 276.477192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:14:21.500388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.068711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4eeb54b4-0507-4c7c-8d41-de8bfefd5aec\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T05:14:21.500434Z","caller":"traceutil/trace.go:171","msg":"trace[506717207] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4eeb54b4-0507-4c7c-8d41-de8bfefd5aec; range_end:; response_count:1; response_revision:1868; }","duration":"101.158653ms","start":"2026-05-19T05:14:21.399262Z","end":"2026-05-19T05:14:21.50042Z","steps":["trace[506717207] 'agreement among raft nodes before linearized reading' (duration: 100.970789ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:14:21.507317Z","caller":"traceutil/trace.go:171","msg":"trace[1408490800] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"107.851074ms","start":"2026-05-19T05:14:21.399448Z","end":"2026-05-19T05:14:21.507299Z","steps":["trace[1408490800] 'process raft request' (duration: 107.714801ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:14:32.785826Z","caller":"traceutil/trace.go:171","msg":"trace[787835833] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"139.343323ms","start":"2026-05-19T05:14:32.646464Z","end":"2026-05-19T05:14:32.785807Z","steps":["trace[787835833] 'process raft request' (duration: 139.16943ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:14:36.542094Z","caller":"traceutil/trace.go:171","msg":"trace[246664577] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"278.982288ms","start":"2026-05-19T05:14:36.263077Z","end":"2026-05-19T05:14:36.54206Z","steps":["trace[246664577] 'process raft request' (duration: 276.891929ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:14:59.44802Z","caller":"traceutil/trace.go:171","msg":"trace[1674430547] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"108.715522ms","start":"2026-05-19T05:14:59.339281Z","end":"2026-05-19T05:14:59.447997Z","steps":["trace[1674430547] 'process raft request' (duration: 108.58095ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:14:59.764464Z","caller":"traceutil/trace.go:171","msg":"trace[1995568414] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"133.39809ms","start":"2026-05-19T05:14:59.631038Z","end":"2026-05-19T05:14:59.764436Z","steps":["trace[1995568414] 'process raft request' (duration: 133.212077ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:15:00.005331Z","caller":"traceutil/trace.go:171","msg":"trace[1170081627] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"218.911848ms","start":"2026-05-19T05:14:59.786362Z","end":"2026-05-19T05:15:00.005273Z","steps":["trace[1170081627] 'process raft request' (duration: 134.903863ms)","trace[1170081627] 'compare' (duration: 83.752031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:15:00.278574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.04134ms","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-05-19T05:15:00.278725Z","caller":"traceutil/trace.go:171","msg":"trace[1204571750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2130; }","duration":"166.255704ms","start":"2026-05-19T05:15:00.112446Z","end":"2026-05-19T05:15:00.278702Z","steps":["trace[1204571750] 'range keys from in-memory index tree' (duration: 165.816127ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:15:49.482416Z","caller":"traceutil/trace.go:171","msg":"trace[1868426467] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"212.165036ms","start":"2026-05-19T05:15:49.270216Z","end":"2026-05-19T05:15:49.482381Z","steps":["trace[1868426467] 'process raft request' (duration: 191.088975ms)","trace[1868426467] 'compare' (duration: 20.795678ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:15:49.484554Z","caller":"traceutil/trace.go:171","msg":"trace[20893947] transaction","detail":"{read_only:false; response_revision:2504; number_of_response:1; }","duration":"213.382139ms","start":"2026-05-19T05:15:49.271157Z","end":"2026-05-19T05:15:49.484539Z","steps":["trace[20893947] 'process raft request' (duration: 213.279078ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:16:23.014096Z","caller":"traceutil/trace.go:171","msg":"trace[144077351] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"218.806737ms","start":"2026-05-19T05:16:22.795256Z","end":"2026-05-19T05:16:23.014062Z","steps":["trace[144077351] 'process raft request' (duration: 128.860655ms)","trace[144077351] 'compare' (duration: 89.743629ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:16:23.01419Z","caller":"traceutil/trace.go:171","msg":"trace[1851115138] linearizableReadLoop","detail":"{readStateIndex:2784; appliedIndex:2783; }","duration":"135.374772ms","start":"2026-05-19T05:16:22.878781Z","end":"2026-05-19T05:16:23.014156Z","steps":["trace[1851115138] 'read index received' (duration: 45.345379ms)","trace[1851115138] 'applied index is now lower than readState.Index' (duration: 90.026333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:16:23.014433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.632974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b0dfa7660f2ef5\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-19T05:16:23.014508Z","caller":"traceutil/trace.go:171","msg":"trace[1136784772] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b0dfa7660f2ef5; range_end:; response_count:1; response_revision:2688; }","duration":"135.770766ms","start":"2026-05-19T05:16:22.878717Z","end":"2026-05-19T05:16:23.014487Z","steps":["trace[1136784772] 'agreement among raft nodes before linearized reading' (duration: 135.541774ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:16:28.187989Z","caller":"traceutil/trace.go:171","msg":"trace[1565625541] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"121.565601ms","start":"2026-05-19T05:16:28.066399Z","end":"2026-05-19T05:16:28.187965Z","steps":["trace[1565625541] 'process raft request' (duration: 121.402498ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:20:14.030727Z","caller":"traceutil/trace.go:171","msg":"trace[1459947404] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4870; }","duration":"255.595877ms","start":"2026-05-19T05:20:13.7751Z","end":"2026-05-19T05:20:14.030696Z","steps":["trace[1459947404] 'read index received' (duration: 255.382872ms)","trace[1459947404] 'applied index is now lower than readState.Index' (duration: 211.195µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:20:14.031238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.914503ms","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-05-19T05:20:14.031356Z","caller":"traceutil/trace.go:171","msg":"trace[127225110] 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:4699; }","duration":"256.266151ms","start":"2026-05-19T05:20:13.775062Z","end":"2026-05-19T05:20:14.031328Z","steps":["trace[127225110] 'agreement among raft nodes before linearized reading' (duration: 255.74404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:20:14.031557Z","caller":"traceutil/trace.go:171","msg":"trace[1813228483] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"302.223247ms","start":"2026-05-19T05:20:13.72929Z","end":"2026-05-19T05:20:14.031513Z","steps":["trace[1813228483] 'process raft request' (duration: 301.231397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:20:14.031783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:20:13.729258Z","time spent":"302.424742ms","remote":"127.0.0.1:51444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:20:14.06738Z","caller":"traceutil/trace.go:171","msg":"trace[1846408909] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"140.094732ms","start":"2026-05-19T05:20:13.927247Z","end":"2026-05-19T05:20:14.067342Z","steps":["trace[1846408909] 'process raft request' (duration: 139.887527ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:20:21.061974Z","caller":"traceutil/trace.go:171","msg":"trace[1577683669] linearizableReadLoop","detail":"{readStateIndex:4998; appliedIndex:4997; }","duration":"138.710791ms","start":"2026-05-19T05:20:20.923237Z","end":"2026-05-19T05:20:21.061948Z","steps":["trace[1577683669] 'read index received' (duration: 84.226135ms)","trace[1577683669] 'applied index is now lower than readState.Index' (duration: 54.483746ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:20:21.062041Z","caller":"traceutil/trace.go:171","msg":"trace[1534431051] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"147.219934ms","start":"2026-05-19T05:20:20.914793Z","end":"2026-05-19T05:20:21.062013Z","steps":["trace[1534431051] 'process raft request' (duration: 92.724018ms)","trace[1534431051] 'compare' (duration: 54.276392ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:20:21.062164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.907345ms","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-05-19T05:20:21.062201Z","caller":"traceutil/trace.go:171","msg":"trace[216873160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4822; }","duration":"139.000117ms","start":"2026-05-19T05:20:20.92319Z","end":"2026-05-19T05:20:21.06219Z","steps":["trace[216873160] 'agreement among raft nodes before linearized reading' (duration: 138.852564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:20:28.538911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.624858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:20:28.539086Z","caller":"traceutil/trace.go:171","msg":"trace[354803661] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"336.917423ms","start":"2026-05-19T05:20:28.202111Z","end":"2026-05-19T05:20:28.539029Z","steps":["trace[354803661] 'process raft request' (duration: 83.07847ms)","trace[354803661] 'compare' (duration: 253.241801ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:20:28.539285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:20:28.202087Z","time spent":"337.090166ms","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:20:28.539272Z","caller":"traceutil/trace.go:171","msg":"trace[1953586760] linearizableReadLoop","detail":"{readStateIndex:5065; appliedIndex:5064; }","duration":"319.268132ms","start":"2026-05-19T05:20:28.219742Z","end":"2026-05-19T05:20:28.539011Z","steps":["trace[1953586760] 'read index received' (duration: 65.354828ms)","trace[1953586760] 'applied index is now lower than readState.Index' (duration: 253.911694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:20:28.539375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.603769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:20:28.539453Z","caller":"traceutil/trace.go:171","msg":"trace[255007759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4887; }","duration":"319.753181ms","start":"2026-05-19T05:20:28.219672Z","end":"2026-05-19T05:20:28.539425Z","steps":["trace[255007759] 'agreement among raft nodes before linearized reading' (duration: 319.609289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:20:28.539502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:20:28.219643Z","time spent":"319.847794ms","remote":"127.0.0.1:51280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T05:20:28.539689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.382715ms","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-05-19T05:20:28.539766Z","caller":"traceutil/trace.go:171","msg":"trace[533600995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4887; }","duration":"248.503657ms","start":"2026-05-19T05:20:28.291243Z","end":"2026-05-19T05:20:28.539747Z","steps":["trace[533600995] 'agreement among raft nodes before linearized reading' (duration: 248.18342ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:22:10.851168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"warn","ts":"2026-05-19T05:22:11.055176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.032386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:22:11.055323Z","caller":"traceutil/trace.go:171","msg":"trace[2081098100] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"148.45405ms","start":"2026-05-19T05:22:10.906841Z","end":"2026-05-19T05:22:11.055295Z","steps":["trace[2081098100] 'process raft request' (duration: 10.17134ms)","trace[2081098100] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:507; } (duration: 137.821862ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:22:11.130636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"277.22066ms","hash":1363981822,"current-db-size-bytes":54349824,"current-db-size":"54 MB","current-db-size-in-use-bytes":29618176,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T05:22:11.130777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1363981822,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-05-19T05:27:10.85755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5679} {"level":"info","ts":"2026-05-19T05:27:10.974478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5679,"took":"115.365891ms","hash":3637158326,"current-db-size-bytes":54349824,"current-db-size":"54 MB","current-db-size-in-use-bytes":18489344,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T05:27:10.974565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3637158326,"revision":5679,"compact-revision":2941}