{"level":"warn","ts":"2026-04-28T13:46:35.880318Z","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-28T13:46:35.88049Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.48: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.19.213.48:2380","--initial-cluster=instance=https://199.19.213.48:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.48:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.48: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-28T13:46:35.880594Z","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-28T13:46:35.880608Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-04-28T13:46:35.880649Z","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-28T13:46:35.88184Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"]} {"level":"info","ts":"2026-04-28T13:46:35.881925Z","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.19.213.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.48: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-28T13:46:35.888304Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.975453ms"} {"level":"info","ts":"2026-04-28T13:46:35.89892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7317567213c7319d","cluster-id":"d22ed1e1757a8527"} {"level":"info","ts":"2026-04-28T13:46:35.899042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T13:46:35.899196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 0"} {"level":"info","ts":"2026-04-28T13:46:35.899224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7317567213c7319d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T13:46:35.89924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 1"} {"level":"info","ts":"2026-04-28T13:46:35.899377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"warn","ts":"2026-04-28T13:46:35.905432Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T13:46:35.906682Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T13:46:35.907777Z","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-28T13:46:35.909749Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7317567213c7319d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T13:46:35.910015Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7317567213c7319d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T13:46:35.910274Z","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-28T13:46:35.91049Z","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-28T13:46:35.910546Z","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-28T13:46:35.914215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"info","ts":"2026-04-28T13:46:35.914669Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","added-peer-id":"7317567213c7319d","added-peer-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-04-28T13:46:35.916921Z","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-28T13:46:35.917246Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7317567213c7319d","initial-advertise-peer-urls":["https://199.19.213.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T13:46:35.917309Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T13:46:35.91727Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-04-28T13:46:35.919517Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-04-28T13:46:36.200084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T13:46:36.200239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T13:46:36.200273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgPreVoteResp from 7317567213c7319d at term 1"} {"level":"info","ts":"2026-04-28T13:46:36.200545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became candidate at term 2"} {"level":"info","ts":"2026-04-28T13:46:36.200571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgVoteResp from 7317567213c7319d at term 2"} {"level":"info","ts":"2026-04-28T13:46:36.200589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became leader at term 2"} {"level":"info","ts":"2026-04-28T13:46:36.200603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7317567213c7319d elected leader 7317567213c7319d at term 2"} {"level":"info","ts":"2026-04-28T13:46:36.202932Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:46:36.204006Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:46:36.204111Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:46:36.204201Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:46:36.204268Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7317567213c7319d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.48:2379]}","request-path":"/0/members/7317567213c7319d/attributes","cluster-id":"d22ed1e1757a8527","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T13:46:36.204277Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:46:36.204374Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:46:36.204792Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T13:46:36.204863Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T13:46:36.208238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.48:2379"} {"level":"info","ts":"2026-04-28T13:46:36.210551Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T13:46:43.998854Z","caller":"traceutil/trace.go:171","msg":"trace[1561198018] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"126.445648ms","start":"2026-04-28T13:46:43.872382Z","end":"2026-04-28T13:46:43.998828Z","steps":["trace[1561198018] 'read index received' (duration: 126.273226ms)","trace[1561198018] 'applied index is now lower than readState.Index' (duration: 171.122µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:46:43.999386Z","caller":"traceutil/trace.go:171","msg":"trace[257071881] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"159.837248ms","start":"2026-04-28T13:46:43.839536Z","end":"2026-04-28T13:46:43.999374Z","steps":["trace[257071881] 'process raft request' (duration: 159.070217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:46:43.999895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.472072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T13:46:43.999961Z","caller":"traceutil/trace.go:171","msg":"trace[372778437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:283; }","duration":"127.640344ms","start":"2026-04-28T13:46:43.872307Z","end":"2026-04-28T13:46:43.999947Z","steps":["trace[372778437] 'agreement among raft nodes before linearized reading' (duration: 127.452962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:46:44.000224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.821953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:46:44.000378Z","caller":"traceutil/trace.go:171","msg":"trace[68033431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:283; }","duration":"127.023396ms","start":"2026-04-28T13:46:43.873331Z","end":"2026-04-28T13:46:44.000354Z","steps":["trace[68033431] 'agreement among raft nodes before linearized reading' (duration: 125.963401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:46:44.379284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.068528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:46:44.379734Z","caller":"traceutil/trace.go:171","msg":"trace[726038666] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"374.747267ms","start":"2026-04-28T13:46:44.004966Z","end":"2026-04-28T13:46:44.379713Z","steps":["trace[726038666] 'process raft request' (duration: 136.699805ms)","trace[726038666] 'compare' (duration: 236.942896ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:46:44.379503Z","caller":"traceutil/trace.go:171","msg":"trace[751214181] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:297; }","duration":"373.275368ms","start":"2026-04-28T13:46:44.006212Z","end":"2026-04-28T13:46:44.379488Z","steps":["trace[751214181] 'read index received' (duration: 135.430449ms)","trace[751214181] 'applied index is now lower than readState.Index' (duration: 237.843789ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:46:44.380055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.804214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-28T13:46:44.380167Z","caller":"traceutil/trace.go:171","msg":"trace[1829926503] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:285; }","duration":"373.955826ms","start":"2026-04-28T13:46:44.00619Z","end":"2026-04-28T13:46:44.380146Z","steps":["trace[1829926503] 'agreement among raft nodes before linearized reading' (duration: 373.719683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:46:44.380222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:46:44.006182Z","time spent":"374.030908ms","remote":"127.0.0.1:35364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-28T13:46:44.380854Z","caller":"traceutil/trace.go:171","msg":"trace[1037139088] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"374.799778ms","start":"2026-04-28T13:46:44.005073Z","end":"2026-04-28T13:46:44.379872Z","steps":["trace[1037139088] 'process raft request' (duration: 374.319991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:46:44.380972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:46:44.00505Z","time spent":"375.856482ms","remote":"127.0.0.1:35498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:46:44.381535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:46:44.004954Z","time spent":"374.890748ms","remote":"127.0.0.1:35498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:46:44.381869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.021675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T13:46:44.381981Z","caller":"traceutil/trace.go:171","msg":"trace[1805190257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:285; }","duration":"319.180746ms","start":"2026-04-28T13:46:44.062787Z","end":"2026-04-28T13:46:44.381967Z","steps":["trace[1805190257] 'agreement among raft nodes before linearized reading' (duration: 319.015284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:46:44.382017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:46:44.062768Z","time spent":"319.241027ms","remote":"127.0.0.1:35498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T13:47:07.961247Z","caller":"traceutil/trace.go:171","msg":"trace[236665712] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"150.724658ms","start":"2026-04-28T13:47:07.810486Z","end":"2026-04-28T13:47:07.961211Z","steps":["trace[236665712] 'process raft request' (duration: 125.328326ms)","trace[236665712] 'compare' (duration: 25.064745ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:47:07.961484Z","caller":"traceutil/trace.go:171","msg":"trace[269069078] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"148.558722ms","start":"2026-04-28T13:47:07.812902Z","end":"2026-04-28T13:47:07.96146Z","steps":["trace[269069078] 'process raft request' (duration: 148.233757ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:07.961583Z","caller":"traceutil/trace.go:171","msg":"trace[1198095904] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"141.836014ms","start":"2026-04-28T13:47:07.819685Z","end":"2026-04-28T13:47:07.961521Z","steps":["trace[1198095904] 'process raft request' (duration: 141.678822ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:07.961646Z","caller":"traceutil/trace.go:171","msg":"trace[194767567] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"135.599173ms","start":"2026-04-28T13:47:07.826028Z","end":"2026-04-28T13:47:07.961628Z","steps":["trace[194767567] 'process raft request' (duration: 135.544962ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:07.961706Z","caller":"traceutil/trace.go:171","msg":"trace[1785046231] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"139.672999ms","start":"2026-04-28T13:47:07.822017Z","end":"2026-04-28T13:47:07.96169Z","steps":["trace[1785046231] 'process raft request' (duration: 139.469376ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:07.961757Z","caller":"traceutil/trace.go:171","msg":"trace[1735906795] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"141.735342ms","start":"2026-04-28T13:47:07.819936Z","end":"2026-04-28T13:47:07.961672Z","steps":["trace[1735906795] 'process raft request' (duration: 141.506718ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:07.961881Z","caller":"traceutil/trace.go:171","msg":"trace[103890441] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"139.71945ms","start":"2026-04-28T13:47:07.82215Z","end":"2026-04-28T13:47:07.96187Z","steps":["trace[103890441] 'process raft request' (duration: 139.374634ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:13.723955Z","caller":"traceutil/trace.go:171","msg":"trace[1961275350] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"112.599014ms","start":"2026-04-28T13:47:13.611319Z","end":"2026-04-28T13:47:13.723918Z","steps":["trace[1961275350] 'process raft request' (duration: 112.418731ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:14.081338Z","caller":"traceutil/trace.go:171","msg":"trace[692728672] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"224.23814ms","start":"2026-04-28T13:47:13.857049Z","end":"2026-04-28T13:47:14.081287Z","steps":["trace[692728672] 'process raft request' (duration: 223.144222ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:14.205903Z","caller":"traceutil/trace.go:171","msg":"trace[627374122] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"118.944879ms","start":"2026-04-28T13:47:14.08693Z","end":"2026-04-28T13:47:14.205875Z","steps":["trace[627374122] 'process raft request' (duration: 75.617554ms)","trace[627374122] 'compare' (duration: 43.201253ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:47:14.270077Z","caller":"traceutil/trace.go:171","msg":"trace[1556292413] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"129.064438ms","start":"2026-04-28T13:47:14.140998Z","end":"2026-04-28T13:47:14.270063Z","steps":["trace[1556292413] 'process raft request' (duration: 128.983317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:14.719601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.288882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:47:14.719687Z","caller":"traceutil/trace.go:171","msg":"trace[1027056368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"187.439995ms","start":"2026-04-28T13:47:14.532229Z","end":"2026-04-28T13:47:14.719669Z","steps":["trace[1027056368] 'range keys from in-memory index tree' (duration: 187.031538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:14.719669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.957203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T13:47:14.719756Z","caller":"traceutil/trace.go:171","msg":"trace[1842757186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"176.051024ms","start":"2026-04-28T13:47:14.543684Z","end":"2026-04-28T13:47:14.719735Z","steps":["trace[1842757186] 'range keys from in-memory index tree' (duration: 175.798271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:14.719827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.422478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T13:47:14.719961Z","caller":"traceutil/trace.go:171","msg":"trace[2138909438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"186.614811ms","start":"2026-04-28T13:47:14.53331Z","end":"2026-04-28T13:47:14.719925Z","steps":["trace[2138909438] 'range keys from in-memory index tree' (duration: 186.195874ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:30.401496Z","caller":"traceutil/trace.go:171","msg":"trace[1888701307] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"124.202188ms","start":"2026-04-28T13:47:30.277268Z","end":"2026-04-28T13:47:30.40147Z","steps":["trace[1888701307] 'read index received' (duration: 124.066256ms)","trace[1888701307] 'applied index is now lower than readState.Index' (duration: 134.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:47:30.401575Z","caller":"traceutil/trace.go:171","msg":"trace[1645438221] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"141.079278ms","start":"2026-04-28T13:47:30.260449Z","end":"2026-04-28T13:47:30.401528Z","steps":["trace[1645438221] 'process raft request' (duration: 140.846634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:30.401721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.430132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:47:30.401758Z","caller":"traceutil/trace.go:171","msg":"trace[1975685334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"124.556685ms","start":"2026-04-28T13:47:30.277194Z","end":"2026-04-28T13:47:30.401751Z","steps":["trace[1975685334] 'agreement among raft nodes before linearized reading' (duration: 124.380182ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:30.128121Z","caller":"traceutil/trace.go:171","msg":"trace[1221968553] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"112.36859ms","start":"2026-04-28T13:48:30.015738Z","end":"2026-04-28T13:48:30.128106Z","steps":["trace[1221968553] 'process raft request' (duration: 112.254218ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:30.349359Z","caller":"traceutil/trace.go:171","msg":"trace[2029492350] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"193.37327ms","start":"2026-04-28T13:49:30.155963Z","end":"2026-04-28T13:49:30.349337Z","steps":["trace[2029492350] 'process raft request' (duration: 193.214797ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:09.111567Z","caller":"traceutil/trace.go:171","msg":"trace[1842035378] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"127.147732ms","start":"2026-04-28T13:55:08.984365Z","end":"2026-04-28T13:55:09.111513Z","steps":["trace[1842035378] 'process raft request' (duration: 92.946792ms)","trace[1842035378] 'compare' (duration: 33.848644ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:09.111596Z","caller":"traceutil/trace.go:171","msg":"trace[1237168406] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"118.244815ms","start":"2026-04-28T13:55:08.993315Z","end":"2026-04-28T13:55:09.11156Z","steps":["trace[1237168406] 'process raft request' (duration: 118.157714ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:09.111755Z","caller":"traceutil/trace.go:171","msg":"trace[1766644781] linearizableReadLoop","detail":"{readStateIndex:4865; appliedIndex:4864; }","duration":"122.78866ms","start":"2026-04-28T13:55:08.988901Z","end":"2026-04-28T13:55:09.11169Z","steps":["trace[1766644781] 'read index received' (duration: 88.425276ms)","trace[1766644781] 'applied index is now lower than readState.Index' (duration: 34.359054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:09.112276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.065695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T13:55:09.112338Z","caller":"traceutil/trace.go:171","msg":"trace[134988921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4691; }","duration":"124.161027ms","start":"2026-04-28T13:55:08.988161Z","end":"2026-04-28T13:55:09.112322Z","steps":["trace[134988921] 'agreement among raft nodes before linearized reading' (duration: 123.705259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:14.427617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.018338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:55:14.427741Z","caller":"traceutil/trace.go:171","msg":"trace[2144024603] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"150.452896ms","start":"2026-04-28T13:55:14.277268Z","end":"2026-04-28T13:55:14.427721Z","steps":["trace[2144024603] 'compare' (duration: 141.638381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:15.208423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.167991ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T13:55:15.20983Z","caller":"traceutil/trace.go:171","msg":"trace[807861563] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"154.085267ms","start":"2026-04-28T13:55:15.055711Z","end":"2026-04-28T13:55:15.209796Z","steps":["trace[807861563] 'process raft request' (duration: 153.640367ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:15.209986Z","caller":"traceutil/trace.go:171","msg":"trace[1852866147] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"154.013065ms","start":"2026-04-28T13:55:15.055962Z","end":"2026-04-28T13:55:15.209975Z","steps":["trace[1852866147] 'process raft request' (duration: 153.75056ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:15.209902Z","caller":"traceutil/trace.go:171","msg":"trace[2076556125] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"152.936595ms","start":"2026-04-28T13:55:15.056933Z","end":"2026-04-28T13:55:15.209869Z","steps":["trace[2076556125] 'process raft request' (duration: 152.840933ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:15.210177Z","caller":"traceutil/trace.go:171","msg":"trace[1381442239] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"154.254879ms","start":"2026-04-28T13:55:15.055839Z","end":"2026-04-28T13:55:15.210094Z","steps":["trace[1381442239] 'process raft request' (duration: 153.807971ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:15.236234Z","caller":"traceutil/trace.go:171","msg":"trace[239775650] linearizableReadLoop","detail":"{readStateIndex:4926; appliedIndex:4921; }","duration":"121.000505ms","start":"2026-04-28T13:55:15.115207Z","end":"2026-04-28T13:55:15.236208Z","steps":["trace[239775650] 'read index received' (duration: 94.083738ms)","trace[239775650] 'applied index is now lower than readState.Index' (duration: 26.915237ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:15.236377Z","caller":"traceutil/trace.go:171","msg":"trace[1587425571] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"170.154979ms","start":"2026-04-28T13:55:15.066204Z","end":"2026-04-28T13:55:15.236359Z","steps":["trace[1587425571] 'process raft request' (duration: 169.823743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:15.236842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.578588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T13:55:15.237042Z","caller":"traceutil/trace.go:171","msg":"trace[1845543535] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4748; }","duration":"102.789092ms","start":"2026-04-28T13:55:15.134187Z","end":"2026-04-28T13:55:15.236976Z","steps":["trace[1845543535] 'agreement among raft nodes before linearized reading' (duration: 102.415155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:15.237174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.81136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-28T13:55:15.237398Z","caller":"traceutil/trace.go:171","msg":"trace[92457053] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4748; }","duration":"122.098915ms","start":"2026-04-28T13:55:15.115155Z","end":"2026-04-28T13:55:15.237254Z","steps":["trace[92457053] 'agreement among raft nodes before linearized reading' (duration: 121.366422ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:22.333165Z","caller":"traceutil/trace.go:171","msg":"trace[1803618338] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"233.037705ms","start":"2026-04-28T13:55:22.100102Z","end":"2026-04-28T13:55:22.333139Z","steps":["trace[1803618338] 'process raft request' (duration: 232.874282ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:27.747347Z","caller":"traceutil/trace.go:171","msg":"trace[157930946] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"149.276642ms","start":"2026-04-28T13:56:27.598022Z","end":"2026-04-28T13:56:27.747299Z","steps":["trace[157930946] 'process raft request' (duration: 148.953536ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:36.406552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2868} {"level":"info","ts":"2026-04-28T13:56:36.486403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2868,"took":"77.953ms","hash":1586782353,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":29204480,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-28T13:56:36.486466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1586782353,"revision":2868,"compact-revision":-1} {"level":"warn","ts":"2026-04-28T13:59:34.819987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.941746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:59:34.820112Z","caller":"traceutil/trace.go:171","msg":"trace[1862687796] linearizableReadLoop","detail":"{readStateIndex:7008; appliedIndex:7007; }","duration":"116.35026ms","start":"2026-04-28T13:59:34.703742Z","end":"2026-04-28T13:59:34.820093Z","steps":["trace[1862687796] 'read index received' (duration: 34.92µs)","trace[1862687796] 'applied index is now lower than readState.Index' (duration: 116.31393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:59:34.820335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.595504ms","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-28T13:59:34.82037Z","caller":"traceutil/trace.go:171","msg":"trace[853886898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6765; }","duration":"116.661436ms","start":"2026-04-28T13:59:34.703697Z","end":"2026-04-28T13:59:34.820358Z","steps":["trace[853886898] 'agreement among raft nodes before linearized reading' (duration: 116.451182ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:59:34.820638Z","caller":"traceutil/trace.go:171","msg":"trace[775889129] transaction","detail":"{read_only:false; response_revision:6765; number_of_response:1; }","duration":"151.987461ms","start":"2026-04-28T13:59:34.668637Z","end":"2026-04-28T13:59:34.820625Z","steps":["trace[775889129] 'process raft request' (duration: 23.304122ms)","trace[775889129] 'compare' (duration: 127.806514ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:59:35.021445Z","caller":"traceutil/trace.go:171","msg":"trace[316610563] linearizableReadLoop","detail":"{readStateIndex:7011; appliedIndex:7010; }","duration":"139.332397ms","start":"2026-04-28T13:59:34.882087Z","end":"2026-04-28T13:59:35.021419Z","steps":["trace[316610563] 'read index received' (duration: 22.041079ms)","trace[316610563] 'applied index is now lower than readState.Index' (duration: 117.290118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:59:35.021704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.589562ms","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-28T13:59:35.021739Z","caller":"traceutil/trace.go:171","msg":"trace[395638093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6767; }","duration":"139.671933ms","start":"2026-04-28T13:59:34.882057Z","end":"2026-04-28T13:59:35.021729Z","steps":["trace[395638093] 'agreement among raft nodes before linearized reading' (duration: 139.524981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:59:35.022132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.581487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T13:59:35.02222Z","caller":"traceutil/trace.go:171","msg":"trace[1712431523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6767; }","duration":"135.70122ms","start":"2026-04-28T13:59:34.886502Z","end":"2026-04-28T13:59:35.022204Z","steps":["trace[1712431523] 'agreement among raft nodes before linearized reading' (duration: 135.479216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:59:35.022514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.411294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-28T13:59:35.022555Z","caller":"traceutil/trace.go:171","msg":"trace[1157630007] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6767; }","duration":"128.465075ms","start":"2026-04-28T13:59:34.894076Z","end":"2026-04-28T13:59:35.022541Z","steps":["trace[1157630007] 'agreement among raft nodes before linearized reading' (duration: 128.373613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:59:35.023277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.754742ms","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-28T13:59:35.023355Z","caller":"traceutil/trace.go:171","msg":"trace[540347011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6767; }","duration":"128.901133ms","start":"2026-04-28T13:59:34.894439Z","end":"2026-04-28T13:59:35.02334Z","steps":["trace[540347011] 'agreement among raft nodes before linearized reading' (duration: 127.65982ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:36.413691Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5524} {"level":"info","ts":"2026-04-28T14:01:36.487786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5524,"took":"71.008041ms","hash":486075896,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":18374656,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-28T14:01:36.487869Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":486075896,"revision":5524,"compact-revision":2868}