{"level":"warn","ts":"2026-06-02T17:20:30.130118Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T17:20:30.130193Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-06-02T17:20:30.13025Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T17:20:30.130262Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-02T17:20:30.130278Z","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-06-02T17:20:30.130903Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-06-02T17:20:30.130979Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-06-02T17:20:30.13585Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.6718ms"} {"level":"info","ts":"2026-06-02T17:20:30.144406Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-06-02T17:20:30.144501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T17:20:30.144525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-06-02T17:20:30.144538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T17:20:30.14455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-06-02T17:20:30.144593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-06-02T17:20:30.151387Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T17:20:30.152711Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T17:20:30.153882Z","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-06-02T17:20:30.155906Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T17:20:30.156043Z","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-06-02T17:20:30.156163Z","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-06-02T17:20:30.15618Z","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-06-02T17:20:30.157378Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T17:20:30.158195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-06-02T17:20:30.158423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-02T17:20:30.161992Z","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-06-02T17:20:30.162212Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T17:20:30.162244Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T17:20:30.162318Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-02T17:20:30.162332Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-02T17:20:30.34555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T17:20:30.345647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T17:20:30.345678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-06-02T17:20:30.345704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-06-02T17:20:30.345722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-02T17:20:30.345741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-06-02T17:20:30.345763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-02T17:20:30.347695Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T17:20:30.348398Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T17:20:30.348417Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:20:30.348276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T17:20:30.352134Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T17:20:30.352419Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T17:20:30.352459Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T17:20:30.352706Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:20:30.352859Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:20:30.352915Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:20:30.358876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"warn","ts":"2026-06-02T17:20:34.962732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.443003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:20:34.963038Z","caller":"traceutil/trace.go:171","msg":"trace[1609391306] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"696.442143ms","start":"2026-06-02T17:20:34.266559Z","end":"2026-06-02T17:20:34.963001Z","steps":["trace[1609391306] 'process raft request' (duration: 176.993331ms)","trace[1609391306] 'compare' (duration: 518.261692ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:20:34.963179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:20:34.266539Z","time spent":"696.591942ms","remote":"127.0.0.1:39686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T17:20:34.963399Z","caller":"traceutil/trace.go:171","msg":"trace[1105218190] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"686.194656ms","start":"2026-06-02T17:20:34.276894Z","end":"2026-06-02T17:20:34.963089Z","steps":["trace[1105218190] 'read index received' (duration: 166.643398ms)","trace[1105218190] 'applied index is now lower than readState.Index' (duration: 519.548458ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:20:34.964166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.253996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:20:34.964349Z","caller":"traceutil/trace.go:171","msg":"trace[618902488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:250; }","duration":"687.429349ms","start":"2026-06-02T17:20:34.276868Z","end":"2026-06-02T17:20:34.964297Z","steps":["trace[618902488] 'agreement among raft nodes before linearized reading' (duration: 686.355627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:20:34.96448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:20:34.276858Z","time spent":"687.60166ms","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T17:20:34.964841Z","caller":"traceutil/trace.go:171","msg":"trace[366968351] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"686.955507ms","start":"2026-06-02T17:20:34.27785Z","end":"2026-06-02T17:20:34.964805Z","steps":["trace[366968351] 'process raft request' (duration: 685.166198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:20:34.96492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.949643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-02T17:20:34.965021Z","caller":"traceutil/trace.go:171","msg":"trace[491866080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:251; }","duration":"688.101213ms","start":"2026-06-02T17:20:34.276897Z","end":"2026-06-02T17:20:34.964998Z","steps":["trace[491866080] 'agreement among raft nodes before linearized reading' (duration: 687.870868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:20:34.965079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:20:34.276878Z","time spent":"688.189479ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T17:20:34.964953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:20:34.277835Z","time spent":"687.072634ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T17:20:34.965341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.165903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-06-02T17:20:34.965472Z","caller":"traceutil/trace.go:171","msg":"trace[1166328552] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:251; }","duration":"438.422961ms","start":"2026-06-02T17:20:34.52703Z","end":"2026-06-02T17:20:34.965453Z","steps":["trace[1166328552] 'agreement among raft nodes before linearized reading' (duration: 437.8315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:20:34.965649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:20:34.527007Z","time spent":"438.624164ms","remote":"127.0.0.1:39682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3730,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-02T17:20:35.121857Z","caller":"traceutil/trace.go:171","msg":"trace[1740584972] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:257; }","duration":"143.008876ms","start":"2026-06-02T17:20:34.978822Z","end":"2026-06-02T17:20:35.121831Z","steps":["trace[1740584972] 'read index received' (duration: 60.911884ms)","trace[1740584972] 'applied index is now lower than readState.Index' (duration: 82.096201ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:20:35.122023Z","caller":"traceutil/trace.go:171","msg":"trace[1513847148] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"147.568371ms","start":"2026-06-02T17:20:34.974435Z","end":"2026-06-02T17:20:35.122003Z","steps":["trace[1513847148] 'process raft request' (duration: 145.772709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:20:35.122056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.21216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:20:35.122163Z","caller":"traceutil/trace.go:171","msg":"trace[178350435] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:253; }","duration":"143.36166ms","start":"2026-06-02T17:20:34.97879Z","end":"2026-06-02T17:20:35.122151Z","steps":["trace[178350435] 'agreement among raft nodes before linearized reading' (duration: 143.21231ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:21:12.65122Z","caller":"traceutil/trace.go:171","msg":"trace[1428996496] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"332.899777ms","start":"2026-06-02T17:21:12.318294Z","end":"2026-06-02T17:21:12.651194Z","steps":["trace[1428996496] 'process raft request' (duration: 332.363208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:21:12.651754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:21:12.318267Z","time spent":"333.031718ms","remote":"127.0.0.1:39588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T17:22:19.98527Z","caller":"traceutil/trace.go:171","msg":"trace[1019785749] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"117.696418ms","start":"2026-06-02T17:22:19.867514Z","end":"2026-06-02T17:22:19.98521Z","steps":["trace[1019785749] 'process raft request' (duration: 117.520359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:22:20.380756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.915489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-02T17:22:20.380848Z","caller":"traceutil/trace.go:171","msg":"trace[1952140852] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1453; }","duration":"117.062298ms","start":"2026-06-02T17:22:20.263762Z","end":"2026-06-02T17:22:20.380824Z","steps":["trace[1952140852] 'range keys from in-memory index tree' (duration: 116.603363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:22:44.974701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.790993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-02T17:22:44.974732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.597174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T17:22:44.974831Z","caller":"traceutil/trace.go:171","msg":"trace[977812010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1866; }","duration":"304.960656ms","start":"2026-06-02T17:22:44.66984Z","end":"2026-06-02T17:22:44.9748Z","steps":["trace[977812010] 'range keys from in-memory index tree' (duration: 304.585029ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:22:44.974868Z","caller":"traceutil/trace.go:171","msg":"trace[1803478606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1866; }","duration":"307.832119ms","start":"2026-06-02T17:22:44.667007Z","end":"2026-06-02T17:22:44.974839Z","steps":["trace[1803478606] 'range keys from in-memory index tree' (duration: 307.281099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:22:44.974885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:22:44.669827Z","time spent":"305.046348ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T17:22:44.974921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:22:44.666973Z","time spent":"307.93751ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T17:22:44.974733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.89066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-02T17:22:44.975077Z","caller":"traceutil/trace.go:171","msg":"trace[1219263342] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"256.842143ms","start":"2026-06-02T17:22:44.718218Z","end":"2026-06-02T17:22:44.97506Z","steps":["trace[1219263342] 'process raft request' (duration: 256.690271ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:22:44.975389Z","caller":"traceutil/trace.go:171","msg":"trace[1653318679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1866; }","duration":"313.522671ms","start":"2026-06-02T17:22:44.66175Z","end":"2026-06-02T17:22:44.975273Z","steps":["trace[1653318679] 'range keys from in-memory index tree' (duration: 312.570474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:22:44.975581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:22:44.661725Z","time spent":"313.789956ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T17:22:45.381214Z","caller":"traceutil/trace.go:171","msg":"trace[613583635] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"239.392714ms","start":"2026-06-02T17:22:45.1418Z","end":"2026-06-02T17:22:45.381193Z","steps":["trace[613583635] 'process raft request' (duration: 239.19445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:23:20.86804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.094852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-02T17:23:20.868376Z","caller":"traceutil/trace.go:171","msg":"trace[880421127] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2115; }","duration":"391.51818ms","start":"2026-06-02T17:23:20.47683Z","end":"2026-06-02T17:23:20.868349Z","steps":["trace[880421127] 'range keys from in-memory index tree' (duration: 390.784127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:23:20.86805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.9742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T17:23:20.868523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.182198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:23:20.868852Z","caller":"traceutil/trace.go:171","msg":"trace[1416828691] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"121.994993ms","start":"2026-06-02T17:23:20.746757Z","end":"2026-06-02T17:23:20.868752Z","steps":["trace[1416828691] 'read index received' (duration: 14.520222ms)","trace[1416828691] 'applied index is now lower than readState.Index' (duration: 107.470031ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:23:20.869012Z","caller":"traceutil/trace.go:171","msg":"trace[306303989] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"202.401272ms","start":"2026-06-02T17:23:20.666593Z","end":"2026-06-02T17:23:20.868995Z","steps":["trace[306303989] 'process raft request' (duration: 94.644908ms)","trace[306303989] 'compare' (duration: 106.706247ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:23:20.869231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:23:20.476784Z","time spent":"392.427202ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-02T17:23:20.86947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.705636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T17:23:20.869508Z","caller":"traceutil/trace.go:171","msg":"trace[616593947] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2116; }","duration":"122.81621ms","start":"2026-06-02T17:23:20.746683Z","end":"2026-06-02T17:23:20.869499Z","steps":["trace[616593947] 'agreement among raft nodes before linearized reading' (duration: 122.511926ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:23:20.868703Z","caller":"traceutil/trace.go:171","msg":"trace[478758995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2115; }","duration":"265.664752ms","start":"2026-06-02T17:23:20.602955Z","end":"2026-06-02T17:23:20.868619Z","steps":["trace[478758995] 'range keys from in-memory index tree' (duration: 264.862996ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:23:21.203055Z","caller":"traceutil/trace.go:171","msg":"trace[278897273] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"101.428793ms","start":"2026-06-02T17:23:21.101583Z","end":"2026-06-02T17:23:21.203012Z","steps":["trace[278897273] 'process raft request' (duration: 101.157441ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:24:57.044778Z","caller":"traceutil/trace.go:171","msg":"trace[2089729172] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"116.473376ms","start":"2026-06-02T17:24:56.928271Z","end":"2026-06-02T17:24:57.044745Z","steps":["trace[2089729172] 'process raft request' (duration: 116.290032ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:25:02.606872Z","caller":"traceutil/trace.go:171","msg":"trace[1638680945] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"160.908493ms","start":"2026-06-02T17:25:02.445927Z","end":"2026-06-02T17:25:02.606836Z","steps":["trace[1638680945] 'process raft request' (duration: 142.462954ms)","trace[1638680945] 'compare' (duration: 17.839405ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:28:44.069391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.32895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:28:44.069577Z","caller":"traceutil/trace.go:171","msg":"trace[368455847] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"259.269552ms","start":"2026-06-02T17:28:43.810289Z","end":"2026-06-02T17:28:44.069559Z","steps":["trace[368455847] 'process raft request' (duration: 259.203669ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:44.069832Z","caller":"traceutil/trace.go:171","msg":"trace[391649082] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"270.760389ms","start":"2026-06-02T17:28:43.79906Z","end":"2026-06-02T17:28:44.06982Z","steps":["trace[391649082] 'process raft request' (duration: 66.928929ms)","trace[391649082] 'compare' (duration: 202.989486ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:28:44.069978Z","caller":"traceutil/trace.go:171","msg":"trace[619714643] linearizableReadLoop","detail":"{readStateIndex:4836; appliedIndex:4834; }","duration":"269.155145ms","start":"2026-06-02T17:28:43.800813Z","end":"2026-06-02T17:28:44.069968Z","steps":["trace[619714643] 'read index received' (duration: 5.52358ms)","trace[619714643] 'applied index is now lower than readState.Index' (duration: 263.630395ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:28:44.070255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.436026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-06-02T17:28:44.070287Z","caller":"traceutil/trace.go:171","msg":"trace[475325686] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4663; }","duration":"269.491498ms","start":"2026-06-02T17:28:43.800785Z","end":"2026-06-02T17:28:44.070277Z","steps":["trace[475325686] 'agreement among raft nodes before linearized reading' (duration: 269.335222ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:44.091862Z","caller":"traceutil/trace.go:171","msg":"trace[1831773817] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"140.045492ms","start":"2026-06-02T17:28:43.951726Z","end":"2026-06-02T17:28:44.091771Z","steps":["trace[1831773817] 'process raft request' (duration: 139.647126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:44.092393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.518817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-686f776777-bkhqf.18b553c295bef477\" ","response":"range_response_count:1 size:896"} {"level":"warn","ts":"2026-06-02T17:28:44.092441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.474656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:28:44.092476Z","caller":"traceutil/trace.go:171","msg":"trace[438950685] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-686f776777-bkhqf.18b553c295bef477; range_end:; response_count:1; response_revision:4664; }","duration":"189.626302ms","start":"2026-06-02T17:28:43.902827Z","end":"2026-06-02T17:28:44.092454Z","steps":["trace[438950685] 'agreement among raft nodes before linearized reading' (duration: 189.358262ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:44.092495Z","caller":"traceutil/trace.go:171","msg":"trace[51794296] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4664; }","duration":"151.57373ms","start":"2026-06-02T17:28:43.940907Z","end":"2026-06-02T17:28:44.09248Z","steps":["trace[51794296] 'agreement among raft nodes before linearized reading' (duration: 151.483167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:44.092561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.975607ms","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":"warn","ts":"2026-06-02T17:28:44.09255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.716356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-06-02T17:28:44.092608Z","caller":"traceutil/trace.go:171","msg":"trace[1282536364] 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:4664; }","duration":"182.03604ms","start":"2026-06-02T17:28:43.910557Z","end":"2026-06-02T17:28:44.092593Z","steps":["trace[1282536364] 'agreement among raft nodes before linearized reading' (duration: 181.880364ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:44.092658Z","caller":"traceutil/trace.go:171","msg":"trace[519057996] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4664; }","duration":"189.860081ms","start":"2026-06-02T17:28:43.902761Z","end":"2026-06-02T17:28:44.092621Z","steps":["trace[519057996] 'agreement among raft nodes before linearized reading' (duration: 189.526378ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:44.546542Z","caller":"traceutil/trace.go:171","msg":"trace[612971409] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"244.680174ms","start":"2026-06-02T17:28:44.301838Z","end":"2026-06-02T17:28:44.546518Z","steps":["trace[612971409] 'process raft request' (duration: 244.532618ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:44.546584Z","caller":"traceutil/trace.go:171","msg":"trace[1867211662] linearizableReadLoop","detail":"{readStateIndex:4858; appliedIndex:4858; }","duration":"198.428215ms","start":"2026-06-02T17:28:44.348128Z","end":"2026-06-02T17:28:44.546557Z","steps":["trace[1867211662] 'read index received' (duration: 198.413645ms)","trace[1867211662] 'applied index is now lower than readState.Index' (duration: 11.82µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:28:44.546862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.430743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-06-02T17:28:44.54687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.392632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-06-02T17:28:44.546923Z","caller":"traceutil/trace.go:171","msg":"trace[486183571] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4684; }","duration":"243.526697ms","start":"2026-06-02T17:28:44.303386Z","end":"2026-06-02T17:28:44.546913Z","steps":["trace[486183571] 'agreement among raft nodes before linearized reading' (duration: 243.285048ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:44.546954Z","caller":"traceutil/trace.go:171","msg":"trace[2031306329] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4684; }","duration":"242.539298ms","start":"2026-06-02T17:28:44.304393Z","end":"2026-06-02T17:28:44.546932Z","steps":["trace[2031306329] 'agreement among raft nodes before linearized reading' (duration: 242.180384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:44.5476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.678903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61379"} {"level":"info","ts":"2026-06-02T17:28:44.547717Z","caller":"traceutil/trace.go:171","msg":"trace[12250265] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4684; }","duration":"244.165752ms","start":"2026-06-02T17:28:44.303526Z","end":"2026-06-02T17:28:44.547692Z","steps":["trace[12250265] 'agreement among raft nodes before linearized reading' (duration: 243.11043ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:44.552987Z","caller":"traceutil/trace.go:171","msg":"trace[309866089] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"189.237819ms","start":"2026-06-02T17:28:44.363719Z","end":"2026-06-02T17:28:44.552957Z","steps":["trace[309866089] 'process raft request' (duration: 189.02081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:44.553278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.829971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:28:44.553329Z","caller":"traceutil/trace.go:171","msg":"trace[271049771] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:0; response_revision:4685; }","duration":"201.907535ms","start":"2026-06-02T17:28:44.351406Z","end":"2026-06-02T17:28:44.553314Z","steps":["trace[271049771] 'agreement among raft nodes before linearized reading' (duration: 201.815751ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:56.939342Z","caller":"traceutil/trace.go:171","msg":"trace[1111346988] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"108.766052ms","start":"2026-06-02T17:28:56.830544Z","end":"2026-06-02T17:28:56.93931Z","steps":["trace[1111346988] 'process raft request' (duration: 108.4856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:29:31.834035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.092486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-02T17:29:31.83413Z","caller":"traceutil/trace.go:171","msg":"trace[1355024757] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5198; }","duration":"158.239234ms","start":"2026-06-02T17:29:31.675869Z","end":"2026-06-02T17:29:31.834108Z","steps":["trace[1355024757] 'range keys from in-memory index tree' (duration: 157.952639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:29:31.834555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.725383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129689"} {"level":"info","ts":"2026-06-02T17:29:31.834641Z","caller":"traceutil/trace.go:171","msg":"trace[2055126556] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5198; }","duration":"154.836458ms","start":"2026-06-02T17:29:31.679785Z","end":"2026-06-02T17:29:31.834621Z","steps":["trace[2055126556] 'range keys from in-memory index tree' (duration: 154.510083ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:30:30.444035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2916} {"level":"info","ts":"2026-06-02T17:30:30.547248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2916,"took":"100.891316ms","hash":1476973033,"current-db-size-bytes":54722560,"current-db-size":"55 MB","current-db-size-in-use-bytes":29724672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T17:30:30.547319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1476973033,"revision":2916,"compact-revision":-1} {"level":"info","ts":"2026-06-02T17:35:30.452603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5636} {"level":"info","ts":"2026-06-02T17:35:30.578611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5636,"took":"124.481862ms","hash":3339020832,"current-db-size-bytes":54722560,"current-db-size":"55 MB","current-db-size-in-use-bytes":18309120,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-02T17:35:30.578677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3339020832,"revision":5636,"compact-revision":2916}