{"level":"warn","ts":"2026-04-16T06:34:58.910703Z","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-16T06:34:58.910856Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-16T06:34:58.910975Z","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-16T06:34:58.910998Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-16T06:34:58.911034Z","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-16T06:34:58.911929Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-04-16T06:34:58.912031Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-16T06:34:58.926436Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.131251ms"} {"level":"info","ts":"2026-04-16T06:34:58.937403Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-04-16T06:34:58.937528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T06:34:58.93756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-04-16T06:34:58.937719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T06:34:58.937744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-04-16T06:34:58.937797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-04-16T06:34:58.943286Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T06:34:58.944777Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T06:34:58.94599Z","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-16T06:34:58.94888Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T06:34:58.949758Z","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-16T06:34:58.950093Z","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-16T06:34:58.950107Z","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-16T06:34:58.950271Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T06:34:58.950619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-04-16T06:34:58.950843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-16T06:34:58.953788Z","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-16T06:34:58.954058Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-16T06:34:58.954108Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-16T06:34:58.954426Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T06:34:58.954492Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T06:34:59.838598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T06:34:59.838657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T06:34:59.838695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-04-16T06:34:59.838711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-04-16T06:34:59.838719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-16T06:34:59.838731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-04-16T06:34:59.838753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-16T06:34:59.83972Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T06:34:59.839781Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:34:59.839857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:34:59.839927Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:34:59.840021Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T06:34:59.840101Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T06:34:59.840964Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:34:59.841084Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:34:59.841121Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:34:59.84595Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T06:34:59.845976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-04-16T06:35:25.483932Z","caller":"traceutil/trace.go:171","msg":"trace[1626199424] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"105.326407ms","start":"2026-04-16T06:35:25.378574Z","end":"2026-04-16T06:35:25.4839Z","steps":["trace[1626199424] 'process raft request' (duration: 105.198174ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:35:50.923589Z","caller":"traceutil/trace.go:171","msg":"trace[2081249781] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"144.915928ms","start":"2026-04-16T06:35:50.778637Z","end":"2026-04-16T06:35:50.923553Z","steps":["trace[2081249781] 'process raft request' (duration: 136.491115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:35:51.45033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.59568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:35:51.450492Z","caller":"traceutil/trace.go:171","msg":"trace[1679301715] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"430.656101ms","start":"2026-04-16T06:35:51.019816Z","end":"2026-04-16T06:35:51.450472Z","steps":["trace[1679301715] 'process raft request' (duration: 99.14192ms)","trace[1679301715] 'compare' (duration: 330.386476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:35:51.450563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:35:51.019776Z","time spent":"430.752273ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:35:51.458828Z","caller":"traceutil/trace.go:171","msg":"trace[1207665901] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"102.773119ms","start":"2026-04-16T06:35:51.356045Z","end":"2026-04-16T06:35:51.458818Z","steps":["trace[1207665901] 'process raft request' (duration: 102.247617ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:36:05.89055Z","caller":"traceutil/trace.go:171","msg":"trace[1306156763] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"181.427436ms","start":"2026-04-16T06:36:05.709092Z","end":"2026-04-16T06:36:05.890519Z","steps":["trace[1306156763] 'process raft request' (duration: 133.397458ms)","trace[1306156763] 'compare' (duration: 47.902726ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:36:38.226842Z","caller":"traceutil/trace.go:171","msg":"trace[970037430] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"205.763542ms","start":"2026-04-16T06:36:38.021044Z","end":"2026-04-16T06:36:38.226807Z","steps":["trace[970037430] 'process raft request' (duration: 205.561468ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:36:38.227223Z","caller":"traceutil/trace.go:171","msg":"trace[886218287] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1262; }","duration":"172.578501ms","start":"2026-04-16T06:36:38.054618Z","end":"2026-04-16T06:36:38.227197Z","steps":["trace[886218287] 'read index received' (duration: 172.571821ms)","trace[886218287] 'applied index is now lower than readState.Index' (duration: 5.44µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:36:38.227679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.98216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T06:36:38.227788Z","caller":"traceutil/trace.go:171","msg":"trace[752046456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"173.208634ms","start":"2026-04-16T06:36:38.054555Z","end":"2026-04-16T06:36:38.227763Z","steps":["trace[752046456] 'agreement among raft nodes before linearized reading' (duration: 172.781275ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:37:02.518718Z","caller":"traceutil/trace.go:171","msg":"trace[313705872] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"140.181788ms","start":"2026-04-16T06:37:02.378519Z","end":"2026-04-16T06:37:02.5187Z","steps":["trace[313705872] 'process raft request' (duration: 140.149727ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:37:02.518912Z","caller":"traceutil/trace.go:171","msg":"trace[234415529] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"140.678718ms","start":"2026-04-16T06:37:02.378186Z","end":"2026-04-16T06:37:02.518865Z","steps":["trace[234415529] 'process raft request' (duration: 81.811119ms)","trace[234415529] 'compare' (duration: 58.581853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:37:26.25036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.541476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-16T06:37:26.250452Z","caller":"traceutil/trace.go:171","msg":"trace[1491684706] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1908; }","duration":"285.678529ms","start":"2026-04-16T06:37:25.964757Z","end":"2026-04-16T06:37:26.250436Z","steps":["trace[1491684706] 'range keys from in-memory index tree' (duration: 285.305712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:37:26.250717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.572112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T06:37:26.250794Z","caller":"traceutil/trace.go:171","msg":"trace[157519725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1908; }","duration":"151.681784ms","start":"2026-04-16T06:37:26.099094Z","end":"2026-04-16T06:37:26.250776Z","steps":["trace[157519725] 'range keys from in-memory index tree' (duration: 151.411379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:37:26.250793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.310547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6fc686-50db-41db-a24c-5cf5d3701d6b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-16T06:37:26.250873Z","caller":"traceutil/trace.go:171","msg":"trace[1232988661] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c6fc686-50db-41db-a24c-5cf5d3701d6b; range_end:; response_count:1; response_revision:1908; }","duration":"227.436639ms","start":"2026-04-16T06:37:26.023416Z","end":"2026-04-16T06:37:26.250853Z","steps":["trace[1232988661] 'range keys from in-memory index tree' (duration: 227.126723ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:37:26.574733Z","caller":"traceutil/trace.go:171","msg":"trace[1738869925] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"110.678127ms","start":"2026-04-16T06:37:26.464015Z","end":"2026-04-16T06:37:26.574693Z","steps":["trace[1738869925] 'process raft request' (duration: 110.418712ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:37:26.699986Z","caller":"traceutil/trace.go:171","msg":"trace[2092255851] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"229.819425ms","start":"2026-04-16T06:37:26.470125Z","end":"2026-04-16T06:37:26.699944Z","steps":["trace[2092255851] 'process raft request' (duration: 168.613759ms)","trace[2092255851] 'compare' (duration: 60.780958ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:37:38.893017Z","caller":"traceutil/trace.go:171","msg":"trace[957246820] linearizableReadLoop","detail":"{readStateIndex:2086; appliedIndex:2085; }","duration":"132.186982ms","start":"2026-04-16T06:37:38.760796Z","end":"2026-04-16T06:37:38.892983Z","steps":["trace[957246820] 'read index received' (duration: 131.961168ms)","trace[957246820] 'applied index is now lower than readState.Index' (duration: 224.144µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:37:38.893133Z","caller":"traceutil/trace.go:171","msg":"trace[1230964434] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"141.962518ms","start":"2026-04-16T06:37:38.75109Z","end":"2026-04-16T06:37:38.893052Z","steps":["trace[1230964434] 'process raft request' (duration: 141.655022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:37:38.893279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.457727ms","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-16T06:37:38.893344Z","caller":"traceutil/trace.go:171","msg":"trace[1197606420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2011; }","duration":"132.57078ms","start":"2026-04-16T06:37:38.760759Z","end":"2026-04-16T06:37:38.893329Z","steps":["trace[1197606420] 'agreement among raft nodes before linearized reading' (duration: 132.356166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:37:38.894644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.206353ms","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-16T06:37:38.89468Z","caller":"traceutil/trace.go:171","msg":"trace[701762281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2012; }","duration":"133.269774ms","start":"2026-04-16T06:37:38.761401Z","end":"2026-04-16T06:37:38.89467Z","steps":["trace[701762281] 'agreement among raft nodes before linearized reading' (duration: 133.170922ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:37:55.485113Z","caller":"traceutil/trace.go:171","msg":"trace[933047565] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"151.105144ms","start":"2026-04-16T06:37:55.333981Z","end":"2026-04-16T06:37:55.485086Z","steps":["trace[933047565] 'process raft request' (duration: 149.663598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:01.178162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.850399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:38:01.178352Z","caller":"traceutil/trace.go:171","msg":"trace[1821871756] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"149.795577ms","start":"2026-04-16T06:38:01.028536Z","end":"2026-04-16T06:38:01.178332Z","steps":["trace[1821871756] 'process raft request' (duration: 37.700234ms)","trace[1821871756] 'compare' (duration: 111.740687ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:38:01.808343Z","caller":"traceutil/trace.go:171","msg":"trace[751469643] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"186.567113ms","start":"2026-04-16T06:38:01.621732Z","end":"2026-04-16T06:38:01.808299Z","steps":["trace[751469643] 'read index received' (duration: 102.571845ms)","trace[751469643] 'applied index is now lower than readState.Index' (duration: 83.993558ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:38:01.808387Z","caller":"traceutil/trace.go:171","msg":"trace[1790183374] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"238.950231ms","start":"2026-04-16T06:38:01.569403Z","end":"2026-04-16T06:38:01.808353Z","steps":["trace[1790183374] 'process raft request' (duration: 154.946032ms)","trace[1790183374] 'compare' (duration: 83.729924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:38:01.808494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.750497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:38:01.808574Z","caller":"traceutil/trace.go:171","msg":"trace[1236944462] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2154; }","duration":"186.883849ms","start":"2026-04-16T06:38:01.621672Z","end":"2026-04-16T06:38:01.808556Z","steps":["trace[1236944462] 'agreement among raft nodes before linearized reading' (duration: 186.750066ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:02.19196Z","caller":"traceutil/trace.go:171","msg":"trace[457971821] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"105.619601ms","start":"2026-04-16T06:38:02.086307Z","end":"2026-04-16T06:38:02.191912Z","steps":["trace[457971821] 'process raft request' (duration: 105.257744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:36.390446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.773913ms","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-16T06:39:36.390508Z","caller":"traceutil/trace.go:171","msg":"trace[2143265763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2765; }","duration":"523.870925ms","start":"2026-04-16T06:39:35.866624Z","end":"2026-04-16T06:39:36.390495Z","steps":["trace[2143265763] 'range keys from in-memory index tree' (duration: 523.639702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:36.390531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:35.866608Z","time spent":"523.917876ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T06:39:36.390536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.821619ms","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-16T06:39:36.390758Z","caller":"traceutil/trace.go:171","msg":"trace[1722983952] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"517.852189ms","start":"2026-04-16T06:39:35.872888Z","end":"2026-04-16T06:39:36.39074Z","steps":["trace[1722983952] 'process raft request' (duration: 517.122436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:36.390905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:35.872871Z","time spent":"517.97573ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:39:36.391929Z","caller":"traceutil/trace.go:171","msg":"trace[1830982386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2765; }","duration":"738.991651ms","start":"2026-04-16T06:39:35.651616Z","end":"2026-04-16T06:39:36.390607Z","steps":["trace[1830982386] 'range keys from in-memory index tree' (duration: 738.674107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:36.392002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:35.651592Z","time spent":"740.390367ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T06:39:36.430188Z","caller":"traceutil/trace.go:171","msg":"trace[1229470522] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"519.11296ms","start":"2026-04-16T06:39:35.911045Z","end":"2026-04-16T06:39:36.430158Z","steps":["trace[1229470522] 'process raft request' (duration: 518.897757ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:39:36.43031Z","caller":"traceutil/trace.go:171","msg":"trace[904350455] linearizableReadLoop","detail":"{readStateIndex:2868; appliedIndex:2866; }","duration":"302.114141ms","start":"2026-04-16T06:39:36.128175Z","end":"2026-04-16T06:39:36.43029Z","steps":["trace[904350455] 'read index received' (duration: 261.894526ms)","trace[904350455] 'applied index is now lower than readState.Index' (duration: 40.218935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:39:36.430484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.271773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-16T06:39:36.430631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:35.911021Z","time spent":"519.260393ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:39:36.430508Z","caller":"traceutil/trace.go:171","msg":"trace[1510075792] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2768; }","duration":"302.376515ms","start":"2026-04-16T06:39:36.128125Z","end":"2026-04-16T06:39:36.430502Z","steps":["trace[1510075792] 'agreement among raft nodes before linearized reading' (duration: 302.224743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:36.430733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:36.1281Z","time spent":"302.62613ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-16T06:39:36.430848Z","caller":"traceutil/trace.go:171","msg":"trace[1449080463] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"222.811542ms","start":"2026-04-16T06:39:36.208029Z","end":"2026-04-16T06:39:36.430841Z","steps":["trace[1449080463] 'process raft request' (duration: 222.055898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:37.942359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.132622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:39:37.942448Z","caller":"traceutil/trace.go:171","msg":"trace[436778338] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2774; }","duration":"831.265964ms","start":"2026-04-16T06:39:37.111164Z","end":"2026-04-16T06:39:37.94243Z","steps":["trace[436778338] 'count revisions from in-memory index tree' (duration: 831.05974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:37.942493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:37.111142Z","time spent":"831.339835ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:39:37.943131Z","caller":"traceutil/trace.go:171","msg":"trace[1821467398] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"485.542339ms","start":"2026-04-16T06:39:37.457576Z","end":"2026-04-16T06:39:37.943119Z","steps":["trace[1821467398] 'process raft request' (duration: 483.964152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:37.943226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:37.457556Z","time spent":"485.61286ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:39:37.943236Z","caller":"traceutil/trace.go:171","msg":"trace[1918213461] linearizableReadLoop","detail":"{readStateIndex:2877; appliedIndex:2875; }","duration":"424.855531ms","start":"2026-04-16T06:39:37.518332Z","end":"2026-04-16T06:39:37.943187Z","steps":["trace[1918213461] 'read index received' (duration: 423.302174ms)","trace[1918213461] 'applied index is now lower than readState.Index' (duration: 1.552307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:39:37.943463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.606163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:39:37.943489Z","caller":"traceutil/trace.go:171","msg":"trace[1879309296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2776; }","duration":"358.661985ms","start":"2026-04-16T06:39:37.584821Z","end":"2026-04-16T06:39:37.943483Z","steps":["trace[1879309296] 'agreement among raft nodes before linearized reading' (duration: 358.601344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:37.943513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.190677ms","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-16T06:39:37.943551Z","caller":"traceutil/trace.go:171","msg":"trace[1336540299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2776; }","duration":"425.259569ms","start":"2026-04-16T06:39:37.518281Z","end":"2026-04-16T06:39:37.943541Z","steps":["trace[1336540299] 'agreement among raft nodes before linearized reading' (duration: 425.113986ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:39:37.943558Z","caller":"traceutil/trace.go:171","msg":"trace[2130499999] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"484.111714ms","start":"2026-04-16T06:39:37.45944Z","end":"2026-04-16T06:39:37.943552Z","steps":["trace[2130499999] 'process raft request' (duration: 483.612244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:39:37.943636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:37.518248Z","time spent":"425.331429ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T06:39:37.943642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:37.459425Z","time spent":"484.155436ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:39:37.943508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:39:37.584811Z","time spent":"358.691105ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T06:39:41.208698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.967438ms","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-16T06:39:41.208748Z","caller":"traceutil/trace.go:171","msg":"trace[1011377931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2794; }","duration":"165.09696ms","start":"2026-04-16T06:39:41.043639Z","end":"2026-04-16T06:39:41.208736Z","steps":["trace[1011377931] 'range keys from in-memory index tree' (duration: 164.833935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:23.117861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.975317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:40:23.11795Z","caller":"traceutil/trace.go:171","msg":"trace[944417162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3011; }","duration":"585.107122ms","start":"2026-04-16T06:40:22.532823Z","end":"2026-04-16T06:40:23.11793Z","steps":["trace[944417162] 'range keys from in-memory index tree' (duration: 584.894444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:23.117989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:22.532803Z","time spent":"585.177144ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T06:40:23.118826Z","caller":"traceutil/trace.go:171","msg":"trace[1842002055] transaction","detail":"{read_only:false; response_revision:3012; number_of_response:1; }","duration":"499.077766ms","start":"2026-04-16T06:40:22.619727Z","end":"2026-04-16T06:40:23.118805Z","steps":["trace[1842002055] 'process raft request' (duration: 493.398048ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:40:23.119003Z","caller":"traceutil/trace.go:171","msg":"trace[1164721443] transaction","detail":"{read_only:false; response_revision:3013; number_of_response:1; }","duration":"482.826172ms","start":"2026-04-16T06:40:22.636165Z","end":"2026-04-16T06:40:23.118991Z","steps":["trace[1164721443] 'process raft request' (duration: 482.564882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:23.119091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:22.636137Z","time spent":"482.917735ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:40:23.119142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.900479ms","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-16T06:40:23.119186Z","caller":"traceutil/trace.go:171","msg":"trace[1351302436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3013; }","duration":"421.970172ms","start":"2026-04-16T06:40:22.697207Z","end":"2026-04-16T06:40:23.119177Z","steps":["trace[1351302436] 'agreement among raft nodes before linearized reading' (duration: 421.827816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:23.119216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:22.697142Z","time spent":"422.066336ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T06:40:23.118993Z","caller":"traceutil/trace.go:171","msg":"trace[1653255171] linearizableReadLoop","detail":"{readStateIndex:3124; appliedIndex:3122; }","duration":"421.753994ms","start":"2026-04-16T06:40:22.697216Z","end":"2026-04-16T06:40:23.11897Z","steps":["trace[1653255171] 'read index received' (duration: 416.051345ms)","trace[1653255171] 'applied index is now lower than readState.Index' (duration: 5.701559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:40:23.119414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.938661ms","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-16T06:40:23.119444Z","caller":"traceutil/trace.go:171","msg":"trace[1221129099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3013; }","duration":"421.991223ms","start":"2026-04-16T06:40:22.697446Z","end":"2026-04-16T06:40:23.119437Z","steps":["trace[1221129099] 'agreement among raft nodes before linearized reading' (duration: 421.897119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:23.119462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:22.697439Z","time spent":"422.019415ms","remote":"127.0.0.1:52176","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-16T06:40:23.119495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.510999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-16T06:40:23.119557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.283792ms","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-16T06:40:23.119579Z","caller":"traceutil/trace.go:171","msg":"trace[441561294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3013; }","duration":"263.333344ms","start":"2026-04-16T06:40:22.856241Z","end":"2026-04-16T06:40:23.119574Z","steps":["trace[441561294] 'agreement among raft nodes before linearized reading' (duration: 263.274152ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:40:23.119582Z","caller":"traceutil/trace.go:171","msg":"trace[349709547] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:3013; }","duration":"258.5647ms","start":"2026-04-16T06:40:22.86097Z","end":"2026-04-16T06:40:23.119534Z","steps":["trace[349709547] 'agreement among raft nodes before linearized reading' (duration: 258.425555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:23.119839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:22.619702Z","time spent":"499.19716ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:43:24.061144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.899126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:43:24.061314Z","caller":"traceutil/trace.go:171","msg":"trace[2077119823] linearizableReadLoop","detail":"{readStateIndex:4867; appliedIndex:4865; }","duration":"278.647541ms","start":"2026-04-16T06:43:23.782645Z","end":"2026-04-16T06:43:24.061292Z","steps":["trace[2077119823] 'read index received' (duration: 96.54921ms)","trace[2077119823] 'applied index is now lower than readState.Index' (duration: 182.097051ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:43:24.061408Z","caller":"traceutil/trace.go:171","msg":"trace[276727935] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"280.158714ms","start":"2026-04-16T06:43:23.781236Z","end":"2026-04-16T06:43:24.061395Z","steps":["trace[276727935] 'process raft request' (duration: 97.94741ms)","trace[276727935] 'compare' (duration: 181.793832ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:43:24.061699Z","caller":"traceutil/trace.go:171","msg":"trace[746628540] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"279.981219ms","start":"2026-04-16T06:43:23.781706Z","end":"2026-04-16T06:43:24.061687Z","steps":["trace[746628540] 'process raft request' (duration: 279.535626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.06199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.333141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-16T06:43:24.062033Z","caller":"traceutil/trace.go:171","msg":"trace[1251129266] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4693; }","duration":"279.396532ms","start":"2026-04-16T06:43:23.782624Z","end":"2026-04-16T06:43:24.06202Z","steps":["trace[1251129266] 'agreement among raft nodes before linearized reading' (duration: 279.219927ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.072978Z","caller":"traceutil/trace.go:171","msg":"trace[861951731] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"137.192058ms","start":"2026-04-16T06:43:23.935742Z","end":"2026-04-16T06:43:24.072934Z","steps":["trace[861951731] 'process raft request' (duration: 137.015793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.073205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.874923ms","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-16T06:43:24.073286Z","caller":"traceutil/trace.go:171","msg":"trace[1689145039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4694; }","duration":"165.994887ms","start":"2026-04-16T06:43:23.907273Z","end":"2026-04-16T06:43:24.073268Z","steps":["trace[1689145039] 'agreement among raft nodes before linearized reading' (duration: 165.76203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.072993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.301603ms","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-16T06:43:24.073389Z","caller":"traceutil/trace.go:171","msg":"trace[1063223026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4694; }","duration":"161.734915ms","start":"2026-04-16T06:43:23.911629Z","end":"2026-04-16T06:43:24.073364Z","steps":["trace[1063223026] 'agreement among raft nodes before linearized reading' (duration: 161.240331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.073709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.664252ms","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-16T06:43:24.07377Z","caller":"traceutil/trace.go:171","msg":"trace[802895119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4694; }","duration":"194.770635ms","start":"2026-04-16T06:43:23.878984Z","end":"2026-04-16T06:43:24.073754Z","steps":["trace[802895119] 'agreement among raft nodes before linearized reading' (duration: 194.100856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.441247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.128004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:43:24.441491Z","caller":"traceutil/trace.go:171","msg":"trace[828013458] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"201.636408ms","start":"2026-04-16T06:43:24.239828Z","end":"2026-04-16T06:43:24.441464Z","steps":["trace[828013458] 'process raft request' (duration: 201.525705ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.441528Z","caller":"traceutil/trace.go:171","msg":"trace[102093127] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"167.591219ms","start":"2026-04-16T06:43:24.273922Z","end":"2026-04-16T06:43:24.441514Z","steps":["trace[102093127] 'process raft request' (duration: 167.564408ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.441696Z","caller":"traceutil/trace.go:171","msg":"trace[638448633] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"204.999314ms","start":"2026-04-16T06:43:24.236687Z","end":"2026-04-16T06:43:24.441686Z","steps":["trace[638448633] 'process raft request' (duration: 60.365766ms)","trace[638448633] 'compare' (duration: 144.035971ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:43:24.441828Z","caller":"traceutil/trace.go:171","msg":"trace[546533817] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"201.743951ms","start":"2026-04-16T06:43:24.240076Z","end":"2026-04-16T06:43:24.44182Z","steps":["trace[546533817] 'process raft request' (duration: 201.317208ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.441912Z","caller":"traceutil/trace.go:171","msg":"trace[2040548522] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"201.595817ms","start":"2026-04-16T06:43:24.240311Z","end":"2026-04-16T06:43:24.441907Z","steps":["trace[2040548522] 'process raft request' (duration: 201.119272ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.441936Z","caller":"traceutil/trace.go:171","msg":"trace[1909975336] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"184.402793ms","start":"2026-04-16T06:43:24.25752Z","end":"2026-04-16T06:43:24.441923Z","steps":["trace[1909975336] 'process raft request' (duration: 183.939199ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.441965Z","caller":"traceutil/trace.go:171","msg":"trace[1072309458] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4879; }","duration":"202.802602ms","start":"2026-04-16T06:43:24.239155Z","end":"2026-04-16T06:43:24.441958Z","steps":["trace[1072309458] 'read index received' (duration: 14.057614ms)","trace[1072309458] 'applied index is now lower than readState.Index' (duration: 188.744088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:43:24.442027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.869294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:43:24.442045Z","caller":"traceutil/trace.go:171","msg":"trace[1663877152] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4712; }","duration":"202.911485ms","start":"2026-04-16T06:43:24.239127Z","end":"2026-04-16T06:43:24.442038Z","steps":["trace[1663877152] 'agreement among raft nodes before linearized reading' (duration: 202.877394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.442201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.683551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-keystone-peer-discovery\" ","response":"range_response_count:1 size:814"} {"level":"warn","ts":"2026-04-16T06:43:24.442212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.949626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-16T06:43:24.442212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.947368ms","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-16T06:43:24.442218Z","caller":"traceutil/trace.go:171","msg":"trace[1263935778] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-keystone-peer-discovery; range_end:; response_count:1; response_revision:4712; }","duration":"184.723391ms","start":"2026-04-16T06:43:24.257489Z","end":"2026-04-16T06:43:24.442213Z","steps":["trace[1263935778] 'agreement among raft nodes before linearized reading' (duration: 184.642859ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.442239Z","caller":"traceutil/trace.go:171","msg":"trace[47420098] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4712; }","duration":"202.993497ms","start":"2026-04-16T06:43:24.239238Z","end":"2026-04-16T06:43:24.442232Z","steps":["trace[47420098] 'agreement among raft nodes before linearized reading' (duration: 202.885154ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.442241Z","caller":"traceutil/trace.go:171","msg":"trace[1895418955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4712; }","duration":"138.016009ms","start":"2026-04-16T06:43:24.304218Z","end":"2026-04-16T06:43:24.442234Z","steps":["trace[1895418955] 'agreement among raft nodes before linearized reading' (duration: 137.915196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.442252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.569951ms","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-16T06:43:24.442283Z","caller":"traceutil/trace.go:171","msg":"trace[1652730228] 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:4712; }","duration":"189.604892ms","start":"2026-04-16T06:43:24.252669Z","end":"2026-04-16T06:43:24.442274Z","steps":["trace[1652730228] 'agreement among raft nodes before linearized reading' (duration: 189.52115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.442372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.948336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-16T06:43:24.442391Z","caller":"traceutil/trace.go:171","msg":"trace[409739504] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4712; }","duration":"202.978397ms","start":"2026-04-16T06:43:24.239407Z","end":"2026-04-16T06:43:24.442385Z","steps":["trace[409739504] 'agreement among raft nodes before linearized reading' (duration: 202.923346ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.749308Z","caller":"traceutil/trace.go:171","msg":"trace[1132680427] linearizableReadLoop","detail":"{readStateIndex:4896; appliedIndex:4893; }","duration":"157.21204ms","start":"2026-04-16T06:43:24.592051Z","end":"2026-04-16T06:43:24.749263Z","steps":["trace[1132680427] 'read index received' (duration: 55.185007ms)","trace[1132680427] 'applied index is now lower than readState.Index' (duration: 102.025893ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:43:24.749594Z","caller":"traceutil/trace.go:171","msg":"trace[1281713168] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"158.870158ms","start":"2026-04-16T06:43:24.590686Z","end":"2026-04-16T06:43:24.749557Z","steps":["trace[1281713168] 'process raft request' (duration: 158.492717ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:24.749654Z","caller":"traceutil/trace.go:171","msg":"trace[1886312225] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"159.476175ms","start":"2026-04-16T06:43:24.590148Z","end":"2026-04-16T06:43:24.749624Z","steps":["trace[1886312225] 'process raft request' (duration: 158.834357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:43:24.749877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.801517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-16T06:43:24.75302Z","caller":"traceutil/trace.go:171","msg":"trace[1924695671] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4722; }","duration":"160.967109ms","start":"2026-04-16T06:43:24.592027Z","end":"2026-04-16T06:43:24.752994Z","steps":["trace[1924695671] 'agreement among raft nodes before linearized reading' (duration: 157.672824ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:43:37.063453Z","caller":"traceutil/trace.go:171","msg":"trace[1998022279] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"189.934309ms","start":"2026-04-16T06:43:36.873491Z","end":"2026-04-16T06:43:37.063425Z","steps":["trace[1998022279] 'process raft request' (duration: 185.39773ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:44:59.890496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-04-16T06:44:59.994346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"102.715567ms","hash":86784372,"current-db-size-bytes":54194176,"current-db-size":"54 MB","current-db-size-in-use-bytes":29380608,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-16T06:44:59.994414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":86784372,"revision":2892,"compact-revision":-1}