{"level":"warn","ts":"2026-04-09T22:23:04.480375Z","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-09T22:23:04.480519Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-09T22:23:04.480624Z","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-09T22:23:04.480641Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-04-09T22:23:04.480671Z","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-09T22:23:04.481741Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-04-09T22:23:04.481864Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-09T22:23:04.48544Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.284919ms"} {"level":"info","ts":"2026-04-09T22:23:04.492202Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-04-09T22:23:04.492285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T22:23:04.492316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-04-09T22:23:04.492332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T22:23:04.49235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-04-09T22:23:04.492401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-04-09T22:23:04.500364Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T22:23:04.501532Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T22:23:04.503129Z","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-09T22:23:04.504867Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T22:23:04.50615Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T22:23:04.50626Z","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-09T22:23:04.506291Z","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-09T22:23:04.506299Z","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-09T22:23:04.506576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-04-09T22:23:04.506633Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-04-09T22:23:04.511129Z","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-09T22:23:04.511268Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-04-09T22:23:04.511303Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-04-09T22:23:04.511481Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T22:23:04.511517Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T22:23:04.89331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T22:23:04.893398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T22:23:04.89344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-04-09T22:23:04.893468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-04-09T22:23:04.893486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-04-09T22:23:04.893564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-04-09T22:23:04.893585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-04-09T22:23:04.895451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T22:23:04.89551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:23:04.895819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:23:04.896102Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T22:23:04.896165Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T22:23:04.896229Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:23:04.896646Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:23:04.896867Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:23:04.896908Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:23:04.898135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T22:23:04.917693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-04-09T22:23:16.639603Z","caller":"traceutil/trace.go:171","msg":"trace[2070704927] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"131.16961ms","start":"2026-04-09T22:23:16.50839Z","end":"2026-04-09T22:23:16.63956Z","steps":["trace[2070704927] 'read index received' (duration: 130.917973ms)","trace[2070704927] 'applied index is now lower than readState.Index' (duration: 250.337µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:23:16.639758Z","caller":"traceutil/trace.go:171","msg":"trace[466632724] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"134.51998ms","start":"2026-04-09T22:23:16.505224Z","end":"2026-04-09T22:23:16.639744Z","steps":["trace[466632724] 'process raft request' (duration: 134.156851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:23:16.64011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.693574ms","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-09T22:23:16.640221Z","caller":"traceutil/trace.go:171","msg":"trace[1911498711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:399; }","duration":"131.836669ms","start":"2026-04-09T22:23:16.508367Z","end":"2026-04-09T22:23:16.640204Z","steps":["trace[1911498711] 'agreement among raft nodes before linearized reading' (duration: 131.691555ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:23:16.948018Z","caller":"traceutil/trace.go:171","msg":"trace[279615251] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"303.44271ms","start":"2026-04-09T22:23:16.644532Z","end":"2026-04-09T22:23:16.947975Z","steps":["trace[279615251] 'process raft request' (duration: 273.356385ms)","trace[279615251] 'compare' (duration: 29.876809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:23:16.948872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:23:16.644496Z","time spent":"303.675137ms","remote":"127.0.0.1:34048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:23:16.957481Z","caller":"traceutil/trace.go:171","msg":"trace[2129575879] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"204.202245ms","start":"2026-04-09T22:23:16.753251Z","end":"2026-04-09T22:23:16.957453Z","steps":["trace[2129575879] 'process raft request' (duration: 204.148693ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:23:16.957598Z","caller":"traceutil/trace.go:171","msg":"trace[2036289858] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"312.610095ms","start":"2026-04-09T22:23:16.644949Z","end":"2026-04-09T22:23:16.957559Z","steps":["trace[2036289858] 'process raft request' (duration: 312.284327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:23:16.958028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:23:16.644933Z","time spent":"312.964865ms","remote":"127.0.0.1:34048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:23:17.11867Z","caller":"traceutil/trace.go:171","msg":"trace[501712789] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"117.360316ms","start":"2026-04-09T22:23:17.001286Z","end":"2026-04-09T22:23:17.118646Z","steps":["trace[501712789] 'process raft request' (duration: 117.177532ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:23:39.059112Z","caller":"traceutil/trace.go:171","msg":"trace[1582716782] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"166.881084ms","start":"2026-04-09T22:23:38.892198Z","end":"2026-04-09T22:23:39.059079Z","steps":["trace[1582716782] 'process raft request' (duration: 164.76228ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:23:41.679387Z","caller":"traceutil/trace.go:171","msg":"trace[819348816] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"184.559799ms","start":"2026-04-09T22:23:41.494782Z","end":"2026-04-09T22:23:41.679342Z","steps":["trace[819348816] 'process raft request' (duration: 184.010514ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:23:42.016267Z","caller":"traceutil/trace.go:171","msg":"trace[896006683] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"265.516041ms","start":"2026-04-09T22:23:41.750711Z","end":"2026-04-09T22:23:42.016227Z","steps":["trace[896006683] 'process raft request' (duration: 241.193087ms)","trace[896006683] 'compare' (duration: 23.833012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:23:42.661478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.095835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:23:42.661806Z","caller":"traceutil/trace.go:171","msg":"trace[546787179] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"588.032561ms","start":"2026-04-09T22:23:42.073583Z","end":"2026-04-09T22:23:42.661616Z","steps":["trace[546787179] 'process raft request' (duration: 373.505365ms)","trace[546787179] 'compare' (duration: 213.768577ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:23:42.662108Z","caller":"traceutil/trace.go:171","msg":"trace[680066478] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"583.621075ms","start":"2026-04-09T22:23:42.078337Z","end":"2026-04-09T22:23:42.661958Z","steps":["trace[680066478] 'read index received' (duration: 368.888664ms)","trace[680066478] 'applied index is now lower than readState.Index' (duration: 214.730011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:23:42.662167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:23:42.073571Z","time spent":"588.427271ms","remote":"127.0.0.1:34048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:23:42.662678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.135179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vggt4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T22:23:42.66282Z","caller":"traceutil/trace.go:171","msg":"trace[2097384659] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vggt4; range_end:; response_count:1; response_revision:622; }","duration":"584.453468ms","start":"2026-04-09T22:23:42.078278Z","end":"2026-04-09T22:23:42.662732Z","steps":["trace[2097384659] 'agreement among raft nodes before linearized reading' (duration: 584.031847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:23:42.663063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:23:42.078252Z","time spent":"584.753805ms","remote":"127.0.0.1:33960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vggt4\" "} {"level":"warn","ts":"2026-04-09T22:23:42.664415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.272112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:23:42.66479Z","caller":"traceutil/trace.go:171","msg":"trace[1307394070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"469.656382ms","start":"2026-04-09T22:23:42.195087Z","end":"2026-04-09T22:23:42.664743Z","steps":["trace[1307394070] 'agreement among raft nodes before linearized reading' (duration: 469.289042ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:23:42.664801Z","caller":"traceutil/trace.go:171","msg":"trace[1609831814] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"230.706559ms","start":"2026-04-09T22:23:42.434069Z","end":"2026-04-09T22:23:42.664776Z","steps":["trace[1609831814] 'process raft request' (duration: 228.02302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:23:42.665053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:23:42.19506Z","time spent":"469.787406ms","remote":"127.0.0.1:33746","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-09T22:24:29.72289Z","caller":"traceutil/trace.go:171","msg":"trace[167561988] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"159.922885ms","start":"2026-04-09T22:24:29.562934Z","end":"2026-04-09T22:24:29.722857Z","steps":["trace[167561988] 'process raft request' (duration: 159.300416ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:25:18.593936Z","caller":"traceutil/trace.go:171","msg":"trace[814767341] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"195.782012ms","start":"2026-04-09T22:25:18.398125Z","end":"2026-04-09T22:25:18.593907Z","steps":["trace[814767341] 'process raft request' (duration: 195.600158ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:25:18.719632Z","caller":"traceutil/trace.go:171","msg":"trace[1387702387] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"114.76811ms","start":"2026-04-09T22:25:18.604836Z","end":"2026-04-09T22:25:18.719604Z","steps":["trace[1387702387] 'read index received' (duration: 78.210136ms)","trace[1387702387] 'applied index is now lower than readState.Index' (duration: 36.556794ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:25:18.719909Z","caller":"traceutil/trace.go:171","msg":"trace[1282424285] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"152.840197ms","start":"2026-04-09T22:25:18.567037Z","end":"2026-04-09T22:25:18.719878Z","steps":["trace[1282424285] 'process raft request' (duration: 116.067727ms)","trace[1282424285] 'compare' (duration: 36.368769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:25:18.72019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.311786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dc026224-f83e-40d0-951e-c26caed63e62\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-09T22:25:18.720817Z","caller":"traceutil/trace.go:171","msg":"trace[1796000654] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dc026224-f83e-40d0-951e-c26caed63e62; range_end:; response_count:1; response_revision:1867; }","duration":"115.987053ms","start":"2026-04-09T22:25:18.604811Z","end":"2026-04-09T22:25:18.720798Z","steps":["trace[1796000654] 'agreement among raft nodes before linearized reading' (duration: 115.076259ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:25:18.873653Z","caller":"traceutil/trace.go:171","msg":"trace[1661377392] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"110.243239ms","start":"2026-04-09T22:25:18.763382Z","end":"2026-04-09T22:25:18.873625Z","steps":["trace[1661377392] 'read index received' (duration: 101.218996ms)","trace[1661377392] 'applied index is now lower than readState.Index' (duration: 9.022923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:25:18.874026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.62986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-09T22:25:18.87412Z","caller":"traceutil/trace.go:171","msg":"trace[339219519] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"110.771113ms","start":"2026-04-09T22:25:18.763326Z","end":"2026-04-09T22:25:18.874097Z","steps":["trace[339219519] 'agreement among raft nodes before linearized reading' (duration: 110.511186ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:25:18.874186Z","caller":"traceutil/trace.go:171","msg":"trace[546273772] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"146.36687ms","start":"2026-04-09T22:25:18.727772Z","end":"2026-04-09T22:25:18.874139Z","steps":["trace[546273772] 'process raft request' (duration: 136.787883ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:25:53.33315Z","caller":"traceutil/trace.go:171","msg":"trace[1129630064] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"184.904721ms","start":"2026-04-09T22:25:53.148204Z","end":"2026-04-09T22:25:53.333109Z","steps":["trace[1129630064] 'process raft request' (duration: 184.621853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:25:53.55894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.829992ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T22:25:53.559123Z","caller":"traceutil/trace.go:171","msg":"trace[1275601378] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"202.918007ms","start":"2026-04-09T22:25:53.356178Z","end":"2026-04-09T22:25:53.559096Z","steps":["trace[1275601378] 'read index received' (duration: 47.89552ms)","trace[1275601378] 'applied index is now lower than readState.Index' (duration: 155.019777ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:25:53.559523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.327428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:25:53.559629Z","caller":"traceutil/trace.go:171","msg":"trace[1020624617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2114; }","duration":"203.446592ms","start":"2026-04-09T22:25:53.356159Z","end":"2026-04-09T22:25:53.559605Z","steps":["trace[1020624617] 'agreement among raft nodes before linearized reading' (duration: 203.02601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:25:53.816129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.509334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:25:53.816772Z","caller":"traceutil/trace.go:171","msg":"trace[520722690] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"250.244771ms","start":"2026-04-09T22:25:53.566502Z","end":"2026-04-09T22:25:53.816747Z","steps":["trace[520722690] 'process raft request' (duration: 134.989357ms)","trace[520722690] 'compare' (duration: 114.32145ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:27:30.666357Z","caller":"traceutil/trace.go:171","msg":"trace[706561686] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"121.817212ms","start":"2026-04-09T22:27:30.544501Z","end":"2026-04-09T22:27:30.666318Z","steps":["trace[706561686] 'process raft request' (duration: 121.569566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:36.456934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.379162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:27:36.457085Z","caller":"traceutil/trace.go:171","msg":"trace[448979913] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2873; }","duration":"107.521008ms","start":"2026-04-09T22:27:36.349547Z","end":"2026-04-09T22:27:36.457068Z","steps":["trace[448979913] 'read index received' (duration: 44.471µs)","trace[448979913] 'applied index is now lower than readState.Index' (duration: 107.475127ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:27:36.457294Z","caller":"traceutil/trace.go:171","msg":"trace[1202455285] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"244.443654ms","start":"2026-04-09T22:27:36.212814Z","end":"2026-04-09T22:27:36.457258Z","steps":["trace[1202455285] 'process raft request' (duration: 115.24786ms)","trace[1202455285] 'compare' (duration: 128.220928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:27:36.457505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.9515ms","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-09T22:27:36.457605Z","caller":"traceutil/trace.go:171","msg":"trace[1653420880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2775; }","duration":"108.090814ms","start":"2026-04-09T22:27:36.349503Z","end":"2026-04-09T22:27:36.457594Z","steps":["trace[1653420880] 'agreement among raft nodes before linearized reading' (duration: 107.863248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:38.437349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.957055ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-09T22:31:38.43759Z","caller":"traceutil/trace.go:171","msg":"trace[993383573] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"164.380437ms","start":"2026-04-09T22:31:38.273189Z","end":"2026-04-09T22:31:38.43757Z","steps":["trace[993383573] 'process raft request' (duration: 164.268754ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:38.437939Z","caller":"traceutil/trace.go:171","msg":"trace[169474105] linearizableReadLoop","detail":"{readStateIndex:4995; appliedIndex:4994; }","duration":"241.222141ms","start":"2026-04-09T22:31:38.19667Z","end":"2026-04-09T22:31:38.437892Z","steps":["trace[169474105] 'read index received' (duration: 110.705632ms)","trace[169474105] 'applied index is now lower than readState.Index' (duration: 130.511579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:31:38.438338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.641781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:31:38.438398Z","caller":"traceutil/trace.go:171","msg":"trace[935753703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4818; }","duration":"241.751774ms","start":"2026-04-09T22:31:38.196631Z","end":"2026-04-09T22:31:38.438382Z","steps":["trace[935753703] 'agreement among raft nodes before linearized reading' (duration: 241.419126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:38.649885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.812275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5db896587d-vzngb.18a4d0f38ba72ee2\" ","response":"range_response_count:1 size:898"} {"level":"warn","ts":"2026-04-09T22:31:38.649905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.875117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16232"} {"level":"info","ts":"2026-04-09T22:31:38.649987Z","caller":"traceutil/trace.go:171","msg":"trace[733879154] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5db896587d-vzngb.18a4d0f38ba72ee2; range_end:; response_count:1; response_revision:4819; }","duration":"108.967279ms","start":"2026-04-09T22:31:38.540996Z","end":"2026-04-09T22:31:38.649963Z","steps":["trace[733879154] 'range keys from in-memory index tree' (duration: 108.623389ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:38.650008Z","caller":"traceutil/trace.go:171","msg":"trace[1307773035] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4819; }","duration":"109.04091ms","start":"2026-04-09T22:31:38.540941Z","end":"2026-04-09T22:31:38.649982Z","steps":["trace[1307773035] 'range keys from in-memory index tree' (duration: 108.613699ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:51.46116Z","caller":"traceutil/trace.go:171","msg":"trace[753885306] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"111.518949ms","start":"2026-04-09T22:31:51.34961Z","end":"2026-04-09T22:31:51.461129Z","steps":["trace[753885306] 'process raft request' (duration: 111.348084ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:33:04.927853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2926} {"level":"info","ts":"2026-04-09T22:33:04.998753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2926,"took":"69.168842ms","hash":620181834,"current-db-size-bytes":53874688,"current-db-size":"54 MB","current-db-size-in-use-bytes":29036544,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-09T22:33:04.998814Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":620181834,"revision":2926,"compact-revision":-1} {"level":"info","ts":"2026-04-09T22:35:48.528768Z","caller":"traceutil/trace.go:171","msg":"trace[425901009] transaction","detail":"{read_only:false; response_revision:6732; number_of_response:1; }","duration":"125.73639ms","start":"2026-04-09T22:35:48.402972Z","end":"2026-04-09T22:35:48.528708Z","steps":["trace[425901009] 'process raft request' (duration: 125.576766ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:04.934285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5593} {"level":"info","ts":"2026-04-09T22:38:05.008313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5593,"took":"72.24567ms","hash":1712370829,"current-db-size-bytes":53874688,"current-db-size":"54 MB","current-db-size-in-use-bytes":18403328,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-09T22:38:05.008374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1712370829,"revision":5593,"compact-revision":2926}