{"level":"warn","ts":"2026-04-08T22:57:21.715537Z","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-08T22:57:21.715674Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-08T22:57:21.715755Z","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-08T22:57:21.715765Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-08T22:57:21.715784Z","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-08T22:57:21.717415Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-08T22:57:21.717481Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-08T22:57:21.722114Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.426628ms"} {"level":"info","ts":"2026-04-08T22:57:21.731121Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-08T22:57:21.731242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T22:57:21.731286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-08T22:57:21.731299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T22:57:21.731319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-08T22:57:21.731374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-08T22:57:21.7422Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T22:57:21.744284Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T22:57:21.746665Z","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-08T22:57:21.749971Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T22:57:21.750176Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T22:57:21.750507Z","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-08T22:57:21.750594Z","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-08T22:57:21.750611Z","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-08T22:57:21.751219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-08T22:57:21.75136Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-08T22:57:21.752835Z","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-08T22:57:21.753057Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T22:57:21.753136Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T22:57:21.753281Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-08T22:57:21.753299Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-08T22:57:21.93158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T22:57:21.931665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T22:57:21.931692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-08T22:57:21.931707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-08T22:57:21.931713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-08T22:57:21.931724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-08T22:57:21.931733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-08T22:57:21.933871Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:57:21.934956Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T22:57:21.934957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:57:21.935385Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:57:21.935483Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:57:21.935508Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:57:21.935531Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:57:21.937353Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T22:57:21.937455Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T22:57:21.940945Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T22:57:21.941364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-08T22:57:39.762454Z","caller":"traceutil/trace.go:171","msg":"trace[430244237] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"296.018702ms","start":"2026-04-08T22:57:39.466412Z","end":"2026-04-08T22:57:39.76243Z","steps":["trace[430244237] 'process raft request' (duration: 295.52541ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:50.774183Z","caller":"traceutil/trace.go:171","msg":"trace[835804963] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"111.259326ms","start":"2026-04-08T22:57:50.662839Z","end":"2026-04-08T22:57:50.774099Z","steps":["trace[835804963] 'read index received' (duration: 105.943317ms)","trace[835804963] 'applied index is now lower than readState.Index' (duration: 5.314419ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:57:50.774466Z","caller":"traceutil/trace.go:171","msg":"trace[1572160470] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"104.3924ms","start":"2026-04-08T22:57:50.670037Z","end":"2026-04-08T22:57:50.77443Z","steps":["trace[1572160470] 'process raft request' (duration: 104.171305ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:50.774649Z","caller":"traceutil/trace.go:171","msg":"trace[1143089447] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"111.917392ms","start":"2026-04-08T22:57:50.662716Z","end":"2026-04-08T22:57:50.774634Z","steps":["trace[1143089447] 'process raft request' (duration: 106.04876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:50.774989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.703708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"warn","ts":"2026-04-08T22:57:50.77499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.108726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-04-08T22:57:50.775134Z","caller":"traceutil/trace.go:171","msg":"trace[559898194] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:502; }","duration":"112.316251ms","start":"2026-04-08T22:57:50.662798Z","end":"2026-04-08T22:57:50.775114Z","steps":["trace[559898194] 'agreement among raft nodes before linearized reading' (duration: 111.964283ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:50.775131Z","caller":"traceutil/trace.go:171","msg":"trace[124703340] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:502; }","duration":"109.880532ms","start":"2026-04-08T22:57:50.665225Z","end":"2026-04-08T22:57:50.775105Z","steps":["trace[124703340] 'agreement among raft nodes before linearized reading' (duration: 109.496783ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:55.948168Z","caller":"traceutil/trace.go:171","msg":"trace[2089314920] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"100.71363ms","start":"2026-04-08T22:57:55.847427Z","end":"2026-04-08T22:57:55.948141Z","steps":["trace[2089314920] 'process raft request' (duration: 100.475103ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:58.546944Z","caller":"traceutil/trace.go:171","msg":"trace[1113292545] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"138.233518ms","start":"2026-04-08T22:57:58.408668Z","end":"2026-04-08T22:57:58.546902Z","steps":["trace[1113292545] 'process raft request' (duration: 137.982662ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:59.016494Z","caller":"traceutil/trace.go:171","msg":"trace[2026250490] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"337.140087ms","start":"2026-04-08T22:57:58.679324Z","end":"2026-04-08T22:57:59.016464Z","steps":["trace[2026250490] 'process raft request' (duration: 336.385499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:59.017104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:58.679278Z","time spent":"337.299261ms","remote":"127.0.0.1:34282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:57:59.602108Z","caller":"traceutil/trace.go:171","msg":"trace[1518933110] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"169.800274ms","start":"2026-04-08T22:57:59.432274Z","end":"2026-04-08T22:57:59.602074Z","steps":["trace[1518933110] 'read index received' (duration: 169.476656ms)","trace[1518933110] 'applied index is now lower than readState.Index' (duration: 322.448µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:57:59.602171Z","caller":"traceutil/trace.go:171","msg":"trace[22274566] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"527.818057ms","start":"2026-04-08T22:57:59.074311Z","end":"2026-04-08T22:57:59.602129Z","steps":["trace[22274566] 'process raft request' (duration: 527.56473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:59.60229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.991698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:57:59.602324Z","caller":"traceutil/trace.go:171","msg":"trace[1675473023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"170.105791ms","start":"2026-04-08T22:57:59.432208Z","end":"2026-04-08T22:57:59.602314Z","steps":["trace[1675473023] 'agreement among raft nodes before linearized reading' (duration: 169.996148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:57:59.602362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:57:59.074284Z","time spent":"527.976401ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:58:50.405408Z","caller":"traceutil/trace.go:171","msg":"trace[1869689315] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"101.850985ms","start":"2026-04-08T22:58:50.303536Z","end":"2026-04-08T22:58:50.405387Z","steps":["trace[1869689315] 'process raft request' (duration: 58.952901ms)","trace[1869689315] 'compare' (duration: 42.602386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:06.566995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.99394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:59:06.567141Z","caller":"traceutil/trace.go:171","msg":"trace[1250858596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"195.201645ms","start":"2026-04-08T22:59:06.371914Z","end":"2026-04-08T22:59:06.567116Z","steps":["trace[1250858596] 'range keys from in-memory index tree' (duration: 194.720713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:06.567107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.737553ms","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-08T22:59:06.56723Z","caller":"traceutil/trace.go:171","msg":"trace[625853393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"196.929137ms","start":"2026-04-08T22:59:06.370276Z","end":"2026-04-08T22:59:06.567205Z","steps":["trace[625853393] 'range keys from in-memory index tree' (duration: 196.533248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:36.759361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.460507ms","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-04-08T22:59:36.75946Z","caller":"traceutil/trace.go:171","msg":"trace[262716413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1858; }","duration":"281.615931ms","start":"2026-04-08T22:59:36.477823Z","end":"2026-04-08T22:59:36.759439Z","steps":["trace[262716413] 'range keys from in-memory index tree' (duration: 281.248931ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:36.90146Z","caller":"traceutil/trace.go:171","msg":"trace[1977001229] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"137.028508ms","start":"2026-04-08T22:59:36.76438Z","end":"2026-04-08T22:59:36.901408Z","steps":["trace[1977001229] 'process raft request' (duration: 136.666938ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:36.901542Z","caller":"traceutil/trace.go:171","msg":"trace[480829251] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"136.173087ms","start":"2026-04-08T22:59:36.765303Z","end":"2026-04-08T22:59:36.901477Z","steps":["trace[480829251] 'process raft request' (duration: 135.950812ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:36.901184Z","caller":"traceutil/trace.go:171","msg":"trace[849479891] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"136.867945ms","start":"2026-04-08T22:59:36.764283Z","end":"2026-04-08T22:59:36.901151Z","steps":["trace[849479891] 'process raft request' (duration: 129.488715ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:37.076116Z","caller":"traceutil/trace.go:171","msg":"trace[185000481] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"133.467042ms","start":"2026-04-08T22:59:36.942625Z","end":"2026-04-08T22:59:37.076092Z","steps":["trace[185000481] 'process raft request' (duration: 79.19716ms)","trace[185000481] 'compare' (duration: 54.164979ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:51.126752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.718985ms","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-08T22:59:51.126852Z","caller":"traceutil/trace.go:171","msg":"trace[485175833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1977; }","duration":"162.905139ms","start":"2026-04-08T22:59:50.963918Z","end":"2026-04-08T22:59:51.126824Z","steps":["trace[485175833] 'range keys from in-memory index tree' (duration: 162.499849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:51.126937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.407641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T22:59:51.127037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.082925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T22:59:51.12707Z","caller":"traceutil/trace.go:171","msg":"trace[1511124501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1977; }","duration":"163.615187ms","start":"2026-04-08T22:59:50.963433Z","end":"2026-04-08T22:59:51.127049Z","steps":["trace[1511124501] 'range keys from in-memory index tree' (duration: 163.179946ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:51.127079Z","caller":"traceutil/trace.go:171","msg":"trace[1410498638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1977; }","duration":"159.125616ms","start":"2026-04-08T22:59:50.967942Z","end":"2026-04-08T22:59:51.127068Z","steps":["trace[1410498638] 'range keys from in-memory index tree' (duration: 158.951192ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:28.21766Z","caller":"traceutil/trace.go:171","msg":"trace[2084753901] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"101.222264ms","start":"2026-04-08T23:00:28.116404Z","end":"2026-04-08T23:00:28.217627Z","steps":["trace[2084753901] 'process raft request' (duration: 100.943767ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:50.214493Z","caller":"traceutil/trace.go:171","msg":"trace[1128503905] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"161.751101ms","start":"2026-04-08T23:01:50.052708Z","end":"2026-04-08T23:01:50.214459Z","steps":["trace[1128503905] 'process raft request' (duration: 161.251869ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:01:50.801481Z","caller":"traceutil/trace.go:171","msg":"trace[760150772] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"311.031335ms","start":"2026-04-08T23:01:50.490434Z","end":"2026-04-08T23:01:50.801466Z","steps":["trace[760150772] 'process raft request' (duration: 310.928942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:01:50.801641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:01:50.490404Z","time spent":"311.163608ms","remote":"127.0.0.1:34434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:01:50.80525Z","caller":"traceutil/trace.go:171","msg":"trace[1185466238] linearizableReadLoop","detail":"{readStateIndex:2948; appliedIndex:2947; }","duration":"183.612377ms","start":"2026-04-08T23:01:50.621615Z","end":"2026-04-08T23:01:50.805227Z","steps":["trace[1185466238] 'read index received' (duration: 179.841495ms)","trace[1185466238] 'applied index is now lower than readState.Index' (duration: 3.769452ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:01:50.805578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.945655ms","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-08T23:01:50.805647Z","caller":"traceutil/trace.go:171","msg":"trace[1106064288] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2835; }","duration":"184.069498ms","start":"2026-04-08T23:01:50.621566Z","end":"2026-04-08T23:01:50.805636Z","steps":["trace[1106064288] 'agreement among raft nodes before linearized reading' (duration: 183.809892ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:47.215797Z","caller":"traceutil/trace.go:171","msg":"trace[412643136] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"123.804391ms","start":"2026-04-08T23:05:47.091959Z","end":"2026-04-08T23:05:47.215763Z","steps":["trace[412643136] 'process raft request' (duration: 117.522937ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:05:47.343119Z","caller":"traceutil/trace.go:171","msg":"trace[870615248] linearizableReadLoop","detail":"{readStateIndex:5075; appliedIndex:5073; }","duration":"240.731893ms","start":"2026-04-08T23:05:47.102361Z","end":"2026-04-08T23:05:47.343093Z","steps":["trace[870615248] 'read index received' (duration: 107.294387ms)","trace[870615248] 'applied index is now lower than readState.Index' (duration: 133.433155ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:05:47.34316Z","caller":"traceutil/trace.go:171","msg":"trace[1273128861] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"244.155286ms","start":"2026-04-08T23:05:47.098977Z","end":"2026-04-08T23:05:47.343132Z","steps":["trace[1273128861] 'process raft request' (duration: 243.842558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:47.343396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.589386ms","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-08T23:05:47.34344Z","caller":"traceutil/trace.go:171","msg":"trace[2066918095] 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:4883; }","duration":"237.687999ms","start":"2026-04-08T23:05:47.105736Z","end":"2026-04-08T23:05:47.343424Z","steps":["trace[2066918095] 'agreement among raft nodes before linearized reading' (duration: 237.521425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:47.343441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.05803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T23:05:47.343531Z","caller":"traceutil/trace.go:171","msg":"trace[366041050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4883; }","duration":"241.203154ms","start":"2026-04-08T23:05:47.102305Z","end":"2026-04-08T23:05:47.343509Z","steps":["trace[366041050] 'agreement among raft nodes before linearized reading' (duration: 240.910817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:47.343441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.680941ms","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-08T23:05:47.343621Z","caller":"traceutil/trace.go:171","msg":"trace[1984930122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4883; }","duration":"231.899686ms","start":"2026-04-08T23:05:47.111705Z","end":"2026-04-08T23:05:47.343605Z","steps":["trace[1984930122] 'agreement among raft nodes before linearized reading' (duration: 231.616789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:05:52.369998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.251596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3811327e-3653-41cc-91b0-7fbe69b2b10b\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-08T23:05:52.370112Z","caller":"traceutil/trace.go:171","msg":"trace[461644510] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3811327e-3653-41cc-91b0-7fbe69b2b10b; range_end:; response_count:1; response_revision:5048; }","duration":"282.44092ms","start":"2026-04-08T23:05:52.087646Z","end":"2026-04-08T23:05:52.370087Z","steps":["trace[461644510] 'range keys from in-memory index tree' (duration: 282.035151ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:06:11.513671Z","caller":"traceutil/trace.go:171","msg":"trace[1056628286] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5593; }","duration":"168.952151ms","start":"2026-04-08T23:06:11.34469Z","end":"2026-04-08T23:06:11.513642Z","steps":["trace[1056628286] 'read index received' (duration: 168.619483ms)","trace[1056628286] 'applied index is now lower than readState.Index' (duration: 331.698µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:06:11.513738Z","caller":"traceutil/trace.go:171","msg":"trace[1528033282] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"380.8284ms","start":"2026-04-08T23:06:11.132869Z","end":"2026-04-08T23:06:11.513698Z","steps":["trace[1528033282] 'process raft request' (duration: 380.535523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:06:11.513937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:06:11.132834Z","time spent":"380.997124ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:06:11.513948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.242298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T23:06:11.514041Z","caller":"traceutil/trace.go:171","msg":"trace[1968414218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5389; }","duration":"169.391721ms","start":"2026-04-08T23:06:11.344629Z","end":"2026-04-08T23:06:11.514021Z","steps":["trace[1968414218] 'agreement among raft nodes before linearized reading' (duration: 169.160136ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:06:11.62285Z","caller":"traceutil/trace.go:171","msg":"trace[328663018] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"104.380666ms","start":"2026-04-08T23:06:11.518436Z","end":"2026-04-08T23:06:11.622817Z","steps":["trace[328663018] 'process raft request' (duration: 103.990187ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:06:11.62657Z","caller":"traceutil/trace.go:171","msg":"trace[2051829442] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"107.188445ms","start":"2026-04-08T23:06:11.519163Z","end":"2026-04-08T23:06:11.626352Z","steps":["trace[2051829442] 'process raft request' (duration: 106.967829ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:06:11.626805Z","caller":"traceutil/trace.go:171","msg":"trace[1783321764] linearizableReadLoop","detail":"{readStateIndex:5596; appliedIndex:5594; }","duration":"107.423281ms","start":"2026-04-08T23:06:11.519358Z","end":"2026-04-08T23:06:11.626781Z","steps":["trace[1783321764] 'read index received' (duration: 103.419313ms)","trace[1783321764] 'applied index is now lower than readState.Index' (duration: 4.001758ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:06:11.626948Z","caller":"traceutil/trace.go:171","msg":"trace[1398798636] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"106.96379ms","start":"2026-04-08T23:06:11.519931Z","end":"2026-04-08T23:06:11.626894Z","steps":["trace[1398798636] 'process raft request' (duration: 106.361254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:06:11.627074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.682377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76f22309-1eb1-488f-bdbb-87f0b494265e\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-04-08T23:06:11.627144Z","caller":"traceutil/trace.go:171","msg":"trace[774513733] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-76f22309-1eb1-488f-bdbb-87f0b494265e; range_end:; response_count:1; response_revision:5392; }","duration":"107.80401ms","start":"2026-04-08T23:06:11.519323Z","end":"2026-04-08T23:06:11.627127Z","steps":["trace[774513733] 'agreement among raft nodes before linearized reading' (duration: 107.533834ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:07:02.000226Z","caller":"traceutil/trace.go:171","msg":"trace[717388474] transaction","detail":"{read_only:false; response_revision:5926; number_of_response:1; }","duration":"111.400046ms","start":"2026-04-08T23:07:01.888801Z","end":"2026-04-08T23:07:02.000201Z","steps":["trace[717388474] 'process raft request' (duration: 111.053948ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:07:22.259728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-04-08T23:07:22.347609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"86.004358ms","hash":1033532571,"current-db-size-bytes":57946112,"current-db-size":"58 MB","current-db-size-in-use-bytes":31387648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-08T23:07:22.347697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1033532571,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-04-08T23:12:13.780102Z","caller":"traceutil/trace.go:171","msg":"trace[1846188907] linearizableReadLoop","detail":"{readStateIndex:8535; appliedIndex:8534; }","duration":"111.275005ms","start":"2026-04-08T23:12:13.668786Z","end":"2026-04-08T23:12:13.780061Z","steps":["trace[1846188907] 'read index received' (duration: 110.789704ms)","trace[1846188907] 'applied index is now lower than readState.Index' (duration: 483.971µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:13.780164Z","caller":"traceutil/trace.go:171","msg":"trace[1639447785] transaction","detail":"{read_only:false; response_revision:8243; number_of_response:1; }","duration":"189.811945ms","start":"2026-04-08T23:12:13.590293Z","end":"2026-04-08T23:12:13.780105Z","steps":["trace[1639447785] 'process raft request' (duration: 189.355034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:13.786354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.548348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-08T23:12:13.786429Z","caller":"traceutil/trace.go:171","msg":"trace[570089962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8243; }","duration":"117.696252ms","start":"2026-04-08T23:12:13.668712Z","end":"2026-04-08T23:12:13.786408Z","steps":["trace[570089962] 'agreement among raft nodes before linearized reading' (duration: 111.486931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:13.78659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.683258ms","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-08T23:12:13.786684Z","caller":"traceutil/trace.go:171","msg":"trace[129456836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8243; }","duration":"100.830442ms","start":"2026-04-08T23:12:13.685832Z","end":"2026-04-08T23:12:13.786663Z","steps":["trace[129456836] 'agreement among raft nodes before linearized reading' (duration: 94.581251ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:13.931235Z","caller":"traceutil/trace.go:171","msg":"trace[593865280] transaction","detail":"{read_only:false; response_revision:8244; number_of_response:1; }","duration":"145.824166ms","start":"2026-04-08T23:12:13.785372Z","end":"2026-04-08T23:12:13.931196Z","steps":["trace[593865280] 'process raft request' (duration: 144.213588ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:13.941454Z","caller":"traceutil/trace.go:171","msg":"trace[408684763] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"150.294506ms","start":"2026-04-08T23:12:13.791131Z","end":"2026-04-08T23:12:13.941425Z","steps":["trace[408684763] 'process raft request' (duration: 150.05829ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:13.94168Z","caller":"traceutil/trace.go:171","msg":"trace[1365031834] transaction","detail":"{read_only:false; response_revision:8246; number_of_response:1; }","duration":"149.481144ms","start":"2026-04-08T23:12:13.792149Z","end":"2026-04-08T23:12:13.941631Z","steps":["trace[1365031834] 'process raft request' (duration: 149.228288ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:22.266031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6096} {"level":"info","ts":"2026-04-08T23:12:22.375904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6096,"took":"106.204153ms","hash":4014571769,"current-db-size-bytes":57946112,"current-db-size":"58 MB","current-db-size-in-use-bytes":23613440,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-08T23:12:22.375999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4014571769,"revision":6096,"compact-revision":3009} {"level":"warn","ts":"2026-04-08T23:12:25.665819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.491693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:12:25.665935Z","caller":"traceutil/trace.go:171","msg":"trace[10316837] linearizableReadLoop","detail":"{readStateIndex:8603; appliedIndex:8602; }","duration":"248.113893ms","start":"2026-04-08T23:12:25.417804Z","end":"2026-04-08T23:12:25.665918Z","steps":["trace[10316837] 'read index received' (duration: 124.134208ms)","trace[10316837] 'applied index is now lower than readState.Index' (duration: 123.978155ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:25.66598Z","caller":"traceutil/trace.go:171","msg":"trace[408232348] transaction","detail":"{read_only:false; response_revision:8307; number_of_response:1; }","duration":"261.414826ms","start":"2026-04-08T23:12:25.404523Z","end":"2026-04-08T23:12:25.665938Z","steps":["trace[408232348] 'process raft request' (duration: 137.464942ms)","trace[408232348] 'compare' (duration: 123.147825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:12:25.666088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.915081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T23:12:25.666136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.327448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-08T23:12:25.666151Z","caller":"traceutil/trace.go:171","msg":"trace[672185142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8307; }","duration":"236.991412ms","start":"2026-04-08T23:12:25.429144Z","end":"2026-04-08T23:12:25.666135Z","steps":["trace[672185142] 'agreement among raft nodes before linearized reading' (duration: 236.89197ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:25.666173Z","caller":"traceutil/trace.go:171","msg":"trace[665125283] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:8307; }","duration":"248.364679ms","start":"2026-04-08T23:12:25.4178Z","end":"2026-04-08T23:12:25.666164Z","steps":["trace[665125283] 'agreement among raft nodes before linearized reading' (duration: 248.211795ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:25.97235Z","caller":"traceutil/trace.go:171","msg":"trace[975214393] linearizableReadLoop","detail":"{readStateIndex:8605; appliedIndex:8604; }","duration":"130.216637ms","start":"2026-04-08T23:12:25.842098Z","end":"2026-04-08T23:12:25.972314Z","steps":["trace[975214393] 'read index received' (duration: 92.040578ms)","trace[975214393] 'applied index is now lower than readState.Index' (duration: 38.174389ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:25.97236Z","caller":"traceutil/trace.go:171","msg":"trace[1974830216] transaction","detail":"{read_only:false; response_revision:8308; number_of_response:1; }","duration":"280.179503ms","start":"2026-04-08T23:12:25.69214Z","end":"2026-04-08T23:12:25.972319Z","steps":["trace[1974830216] 'process raft request' (duration: 241.958954ms)","trace[1974830216] 'compare' (duration: 37.963733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:12:25.972826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.688268ms","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-08T23:12:25.972936Z","caller":"traceutil/trace.go:171","msg":"trace[8508824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8308; }","duration":"130.903714ms","start":"2026-04-08T23:12:25.842008Z","end":"2026-04-08T23:12:25.972912Z","steps":["trace[8508824] 'agreement among raft nodes before linearized reading' (duration: 130.505854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:26.759933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.311272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-04-08T23:12:26.760012Z","caller":"traceutil/trace.go:171","msg":"trace[1367604789] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8314; }","duration":"177.456305ms","start":"2026-04-08T23:12:26.582538Z","end":"2026-04-08T23:12:26.759994Z","steps":["trace[1367604789] 'range keys from in-memory index tree' (duration: 177.058616ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:28.293784Z","caller":"traceutil/trace.go:171","msg":"trace[138130334] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"115.910579ms","start":"2026-04-08T23:12:28.177845Z","end":"2026-04-08T23:12:28.293755Z","steps":["trace[138130334] 'process raft request' (duration: 115.724875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.744203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.302824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:12:56.744435Z","caller":"traceutil/trace.go:171","msg":"trace[1145115643] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"562.111779ms","start":"2026-04-08T23:12:56.182269Z","end":"2026-04-08T23:12:56.744381Z","steps":["trace[1145115643] 'process raft request' (duration: 124.525748ms)","trace[1145115643] 'compare' (duration: 436.7289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:12:56.744622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:56.182256Z","time spent":"562.270392ms","remote":"127.0.0.1:34434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T23:12:56.744588Z","caller":"traceutil/trace.go:171","msg":"trace[1237075684] linearizableReadLoop","detail":"{readStateIndex:8830; appliedIndex:8829; }","duration":"535.183134ms","start":"2026-04-08T23:12:56.209355Z","end":"2026-04-08T23:12:56.744538Z","steps":["trace[1237075684] 'read index received' (duration: 97.4604ms)","trace[1237075684] 'applied index is now lower than readState.Index' (duration: 437.719344ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:12:56.744703Z","caller":"traceutil/trace.go:171","msg":"trace[1400611941] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"108.052497ms","start":"2026-04-08T23:12:56.636625Z","end":"2026-04-08T23:12:56.744678Z","steps":["trace[1400611941] 'process raft request' (duration: 107.995265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.744812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.45006ms","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":"warn","ts":"2026-04-08T23:12:56.74483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.049827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:12:56.744886Z","caller":"traceutil/trace.go:171","msg":"trace[1353799281] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8527; }","duration":"173.130589ms","start":"2026-04-08T23:12:56.57174Z","end":"2026-04-08T23:12:56.744871Z","steps":["trace[1353799281] 'agreement among raft nodes before linearized reading' (duration: 172.995656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.744894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.987023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T23:12:56.744899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.22783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:12:56.744888Z","caller":"traceutil/trace.go:171","msg":"trace[1847738336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8527; }","duration":"535.574263ms","start":"2026-04-08T23:12:56.209293Z","end":"2026-04-08T23:12:56.744867Z","steps":["trace[1847738336] 'agreement among raft nodes before linearized reading' (duration: 535.369518ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:56.74497Z","caller":"traceutil/trace.go:171","msg":"trace[2095618407] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8527; }","duration":"103.095637ms","start":"2026-04-08T23:12:56.641852Z","end":"2026-04-08T23:12:56.744948Z","steps":["trace[2095618407] 'agreement among raft nodes before linearized reading' (duration: 102.915382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.745042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.07741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4dgrp\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-08T23:12:56.74503Z","caller":"traceutil/trace.go:171","msg":"trace[1098498986] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8527; }","duration":"490.330704ms","start":"2026-04-08T23:12:56.254626Z","end":"2026-04-08T23:12:56.744957Z","steps":["trace[1098498986] 'agreement among raft nodes before linearized reading' (duration: 490.19905ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:12:56.745061Z","caller":"traceutil/trace.go:171","msg":"trace[1751968356] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4dgrp; range_end:; response_count:1; response_revision:8527; }","duration":"201.166862ms","start":"2026-04-08T23:12:56.54389Z","end":"2026-04-08T23:12:56.745057Z","steps":["trace[1751968356] 'agreement among raft nodes before linearized reading' (duration: 201.029859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.745055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:56.209268Z","time spent":"535.766069ms","remote":"127.0.0.1:34434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T23:12:56.744978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.320735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T23:12:56.74509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:56.254619Z","time spent":"490.453047ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-08T23:12:56.745157Z","caller":"traceutil/trace.go:171","msg":"trace[2022524776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8527; }","duration":"312.561231ms","start":"2026-04-08T23:12:56.432562Z","end":"2026-04-08T23:12:56.745123Z","steps":["trace[2022524776] 'agreement among raft nodes before linearized reading' (duration: 312.260103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.745225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:56.432536Z","time spent":"312.677914ms","remote":"127.0.0.1:34184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T23:12:56.744855Z","caller":"traceutil/trace.go:171","msg":"trace[700655350] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"507.439619ms","start":"2026-04-08T23:12:56.237333Z","end":"2026-04-08T23:12:56.744772Z","steps":["trace[700655350] 'process raft request' (duration: 507.04608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.745715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:56.23729Z","time spent":"508.317252ms","remote":"127.0.0.1:34434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T23:12:56.745982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.916379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-08T23:12:56.746061Z","caller":"traceutil/trace.go:171","msg":"trace[1934848970] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8527; }","duration":"285.032041ms","start":"2026-04-08T23:12:56.461009Z","end":"2026-04-08T23:12:56.746041Z","steps":["trace[1934848970] 'agreement among raft nodes before linearized reading' (duration: 283.788331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.745771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.231676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:12:56.746872Z","caller":"traceutil/trace.go:171","msg":"trace[1651559670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8527; }","duration":"492.377384ms","start":"2026-04-08T23:12:56.25447Z","end":"2026-04-08T23:12:56.746848Z","steps":["trace[1651559670] 'agreement among raft nodes before linearized reading' (duration: 490.269152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:12:56.747349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T23:12:56.254454Z","time spent":"492.496066ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T23:12:57.750944Z","caller":"traceutil/trace.go:171","msg":"trace[1258872379] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"102.573865ms","start":"2026-04-08T23:12:57.648339Z","end":"2026-04-08T23:12:57.750913Z","steps":["trace[1258872379] 'process raft request' (duration: 101.525469ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:09.042374Z","caller":"traceutil/trace.go:171","msg":"trace[1779680990] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8632; }","duration":"156.139797ms","start":"2026-04-08T23:13:08.886208Z","end":"2026-04-08T23:13:09.042348Z","steps":["trace[1779680990] 'process raft request' (duration: 133.613299ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:09.126774Z","caller":"traceutil/trace.go:171","msg":"trace[2099008338] linearizableReadLoop","detail":"{readStateIndex:8943; appliedIndex:8941; }","duration":"223.274869ms","start":"2026-04-08T23:13:08.903476Z","end":"2026-04-08T23:13:09.126751Z","steps":["trace[2099008338] 'read index received' (duration: 116.3682ms)","trace[2099008338] 'applied index is now lower than readState.Index' (duration: 106.905899ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:13:09.126867Z","caller":"traceutil/trace.go:171","msg":"trace[541837167] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"237.028604ms","start":"2026-04-08T23:13:08.889787Z","end":"2026-04-08T23:13:09.126816Z","steps":["trace[541837167] 'process raft request' (duration: 236.804359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:13:09.127079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.563087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:13:09.127188Z","caller":"traceutil/trace.go:171","msg":"trace[256593477] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8633; }","duration":"223.70383ms","start":"2026-04-08T23:13:08.90346Z","end":"2026-04-08T23:13:09.127164Z","steps":["trace[256593477] 'agreement among raft nodes before linearized reading' (duration: 223.429963ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:09.823354Z","caller":"traceutil/trace.go:171","msg":"trace[1199529107] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"254.830038ms","start":"2026-04-08T23:13:09.568476Z","end":"2026-04-08T23:13:09.823306Z","steps":["trace[1199529107] 'process raft request' (duration: 254.637343ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:09.840214Z","caller":"traceutil/trace.go:171","msg":"trace[512282337] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"257.346118ms","start":"2026-04-08T23:13:09.582839Z","end":"2026-04-08T23:13:09.840185Z","steps":["trace[512282337] 'process raft request' (duration: 257.142013ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:09.840454Z","caller":"traceutil/trace.go:171","msg":"trace[148036167] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"220.183784ms","start":"2026-04-08T23:13:09.620236Z","end":"2026-04-08T23:13:09.84042Z","steps":["trace[148036167] 'process raft request' (duration: 219.896007ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:10.03271Z","caller":"traceutil/trace.go:171","msg":"trace[665002945] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"161.792344ms","start":"2026-04-08T23:13:09.870882Z","end":"2026-04-08T23:13:10.032674Z","steps":["trace[665002945] 'process raft request' (duration: 161.59855ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:10.049942Z","caller":"traceutil/trace.go:171","msg":"trace[680462796] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"173.634082ms","start":"2026-04-08T23:13:09.876281Z","end":"2026-04-08T23:13:10.049915Z","steps":["trace[680462796] 'process raft request' (duration: 173.454638ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:13:50.808692Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002480.wal"} {"level":"info","ts":"2026-04-08T23:14:24.242615Z","caller":"traceutil/trace.go:171","msg":"trace[1581780770] transaction","detail":"{read_only:false; response_revision:9377; number_of_response:1; }","duration":"102.566447ms","start":"2026-04-08T23:14:24.140013Z","end":"2026-04-08T23:14:24.242579Z","steps":["trace[1581780770] 'process raft request' (duration: 102.324582ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:14:24.294521Z","caller":"traceutil/trace.go:171","msg":"trace[551503568] transaction","detail":"{read_only:false; response_revision:9378; number_of_response:1; }","duration":"148.058251ms","start":"2026-04-08T23:14:24.146416Z","end":"2026-04-08T23:14:24.294474Z","steps":["trace[551503568] 'process raft request' (duration: 147.744204ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:15:02.386601Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T23:15:02.39238Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T23:15:02.392493Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T23:17:22.272526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8292} {"level":"info","ts":"2026-04-08T23:17:22.355683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8292,"took":"80.426591ms","hash":1979678873,"current-db-size-bytes":57946112,"current-db-size":"58 MB","current-db-size-in-use-bytes":25530368,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-08T23:17:22.355763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1979678873,"revision":8292,"compact-revision":6096} {"level":"info","ts":"2026-04-08T23:22:22.281334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10689} {"level":"info","ts":"2026-04-08T23:22:22.358484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10689,"took":"75.048389ms","hash":1569910743,"current-db-size-bytes":57946112,"current-db-size":"58 MB","current-db-size-in-use-bytes":20406272,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T23:22:22.358544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1569910743,"revision":10689,"compact-revision":8292}