{"level":"warn","ts":"2026-04-20T09:33:16.041222Z","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-20T09:33:16.041336Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.203:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.203:2380","--initial-cluster=instance=https://199.19.213.203:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.203:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.203: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-20T09:33:16.041459Z","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-20T09:33:16.041488Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.203:2380"]} {"level":"info","ts":"2026-04-20T09:33:16.041528Z","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-20T09:33:16.042678Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"]} {"level":"info","ts":"2026-04-20T09:33:16.042813Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.203:2380"],"listen-peer-urls":["https://199.19.213.203:2380"],"advertise-client-urls":["https://199.19.213.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.203: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-20T09:33:16.046621Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.461639ms"} {"level":"info","ts":"2026-04-20T09:33:16.053275Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b5a1dacc8ba86a4f","cluster-id":"a3ec0b489c26f222"} {"level":"info","ts":"2026-04-20T09:33:16.053366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T09:33:16.053396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became follower at term 0"} {"level":"info","ts":"2026-04-20T09:33:16.053413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b5a1dacc8ba86a4f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T09:33:16.053423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became follower at term 1"} {"level":"info","ts":"2026-04-20T09:33:16.053466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=(13087982564166625871)"} {"level":"warn","ts":"2026-04-20T09:33:16.058505Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T09:33:16.059867Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T09:33:16.061033Z","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-20T09:33:16.063813Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b5a1dacc8ba86a4f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T09:33:16.067417Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b5a1dacc8ba86a4f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T09:33:16.067462Z","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-20T09:33:16.067598Z","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-20T09:33:16.067639Z","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-20T09:33:16.0691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=(13087982564166625871)"} {"level":"info","ts":"2026-04-20T09:33:16.069266Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a3ec0b489c26f222","local-member-id":"b5a1dacc8ba86a4f","added-peer-id":"b5a1dacc8ba86a4f","added-peer-peer-urls":["https://199.19.213.203:2380"]} {"level":"info","ts":"2026-04-20T09:33:16.069759Z","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-20T09:33:16.069797Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.203:2380"} {"level":"info","ts":"2026-04-20T09:33:16.069844Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.203:2380"} {"level":"info","ts":"2026-04-20T09:33:16.070055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b5a1dacc8ba86a4f","initial-advertise-peer-urls":["https://199.19.213.203:2380"],"listen-peer-urls":["https://199.19.213.203:2380"],"advertise-client-urls":["https://199.19.213.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T09:33:16.070343Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T09:33:16.25403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T09:33:16.254098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T09:33:16.25414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f received MsgPreVoteResp from b5a1dacc8ba86a4f at term 1"} {"level":"info","ts":"2026-04-20T09:33:16.25416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became candidate at term 2"} {"level":"info","ts":"2026-04-20T09:33:16.254172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f received MsgVoteResp from b5a1dacc8ba86a4f at term 2"} {"level":"info","ts":"2026-04-20T09:33:16.254359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became leader at term 2"} {"level":"info","ts":"2026-04-20T09:33:16.254374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b5a1dacc8ba86a4f elected leader b5a1dacc8ba86a4f at term 2"} {"level":"info","ts":"2026-04-20T09:33:16.256301Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:33:16.256406Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b5a1dacc8ba86a4f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.203:2379]}","request-path":"/0/members/b5a1dacc8ba86a4f/attributes","cluster-id":"a3ec0b489c26f222","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T09:33:16.256647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T09:33:16.2567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T09:33:16.25728Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a3ec0b489c26f222","local-member-id":"b5a1dacc8ba86a4f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:33:16.257385Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:33:16.25742Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:33:16.257444Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T09:33:16.25747Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T09:33:16.260486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.203:2379"} {"level":"info","ts":"2026-04-20T09:33:16.260514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T09:33:18.483306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.987437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-20T09:33:18.483565Z","caller":"traceutil/trace.go:171","msg":"trace[1573331707] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"144.771098ms","start":"2026-04-20T09:33:18.338739Z","end":"2026-04-20T09:33:18.48351Z","steps":["trace[1573331707] 'read index received' (duration: 43.261µs)","trace[1573331707] 'applied index is now lower than readState.Index' (duration: 144.726517ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:33:18.483669Z","caller":"traceutil/trace.go:171","msg":"trace[352420733] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"298.1773ms","start":"2026-04-20T09:33:18.18545Z","end":"2026-04-20T09:33:18.483627Z","steps":["trace[352420733] 'process raft request' (duration: 96.294378ms)","trace[352420733] 'compare' (duration: 200.83622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:33:18.483935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.186315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T09:33:18.484043Z","caller":"traceutil/trace.go:171","msg":"trace[1755496618] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:74; }","duration":"145.331671ms","start":"2026-04-20T09:33:18.338695Z","end":"2026-04-20T09:33:18.484026Z","steps":["trace[1755496618] 'agreement among raft nodes before linearized reading' (duration: 145.007668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:33:18.48395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.475894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T09:33:18.484117Z","caller":"traceutil/trace.go:171","msg":"trace[163611947] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:74; }","duration":"124.228344ms","start":"2026-04-20T09:33:18.359873Z","end":"2026-04-20T09:33:18.484102Z","steps":["trace[163611947] 'agreement among raft nodes before linearized reading' (duration: 123.88202ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:33:18.64318Z","caller":"traceutil/trace.go:171","msg":"trace[433236827] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"155.556037ms","start":"2026-04-20T09:33:18.487597Z","end":"2026-04-20T09:33:18.643153Z","steps":["trace[433236827] 'process raft request' (duration: 155.435462ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:33:18.658247Z","caller":"traceutil/trace.go:171","msg":"trace[2086410348] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"167.819853ms","start":"2026-04-20T09:33:18.490398Z","end":"2026-04-20T09:33:18.658218Z","steps":["trace[2086410348] 'process raft request' (duration: 167.637326ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:33:18.65825Z","caller":"traceutil/trace.go:171","msg":"trace[1787915929] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"167.72958ms","start":"2026-04-20T09:33:18.490483Z","end":"2026-04-20T09:33:18.658213Z","steps":["trace[1787915929] 'process raft request' (duration: 167.688878ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:33:51.292972Z","caller":"traceutil/trace.go:171","msg":"trace[126403774] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"158.696883ms","start":"2026-04-20T09:33:51.134247Z","end":"2026-04-20T09:33:51.292944Z","steps":["trace[126403774] 'process raft request' (duration: 158.182159ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:33:51.551216Z","caller":"traceutil/trace.go:171","msg":"trace[147235600] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"238.42902ms","start":"2026-04-20T09:33:51.312764Z","end":"2026-04-20T09:33:51.551193Z","steps":["trace[147235600] 'process raft request' (duration: 238.254689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:33:52.10444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.531183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:33:52.104647Z","caller":"traceutil/trace.go:171","msg":"trace[581375509] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"474.56375ms","start":"2026-04-20T09:33:51.630066Z","end":"2026-04-20T09:33:52.10463Z","steps":["trace[581375509] 'process raft request' (duration: 474.52253ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:33:52.104717Z","caller":"traceutil/trace.go:171","msg":"trace[1585746026] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:635; }","duration":"656.926318ms","start":"2026-04-20T09:33:51.447765Z","end":"2026-04-20T09:33:52.104691Z","steps":["trace[1585746026] 'read index received' (duration: 103.318834ms)","trace[1585746026] 'applied index is now lower than readState.Index' (duration: 553.605204ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:33:52.104824Z","caller":"traceutil/trace.go:171","msg":"trace[1404514721] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"679.727709ms","start":"2026-04-20T09:33:51.425086Z","end":"2026-04-20T09:33:52.104814Z","steps":["trace[1404514721] 'process raft request' (duration: 679.440567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:33:52.1049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:33:51.630038Z","time spent":"474.64095ms","remote":"127.0.0.1:60062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:33:52.104938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:33:51.425045Z","time spent":"679.82122ms","remote":"127.0.0.1:60156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:33:52.105017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.90562ms","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-20T09:33:52.105062Z","caller":"traceutil/trace.go:171","msg":"trace[1141330754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"322.97652ms","start":"2026-04-20T09:33:51.782071Z","end":"2026-04-20T09:33:52.105048Z","steps":["trace[1141330754] 'agreement among raft nodes before linearized reading' (duration: 322.90383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:33:52.105076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.315861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:33:52.105094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:33:51.782053Z","time spent":"323.03436ms","remote":"127.0.0.1:60156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T09:33:52.105107Z","caller":"traceutil/trace.go:171","msg":"trace[676065619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"657.382732ms","start":"2026-04-20T09:33:51.447713Z","end":"2026-04-20T09:33:52.105096Z","steps":["trace[676065619] 'agreement among raft nodes before linearized reading' (duration: 657.330782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:33:52.105134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:33:51.447701Z","time spent":"657.427842ms","remote":"127.0.0.1:59898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T09:33:52.105185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.579597ms","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-20T09:33:52.10521Z","caller":"traceutil/trace.go:171","msg":"trace[990265476] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"727.050543ms","start":"2026-04-20T09:33:51.378146Z","end":"2026-04-20T09:33:52.105196Z","steps":["trace[990265476] 'process raft request' (duration: 348.699615ms)","trace[990265476] 'compare' (duration: 377.390942ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:33:52.105225Z","caller":"traceutil/trace.go:171","msg":"trace[1135466064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"322.629418ms","start":"2026-04-20T09:33:51.782576Z","end":"2026-04-20T09:33:52.105205Z","steps":["trace[1135466064] 'agreement among raft nodes before linearized reading' (duration: 322.583667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:33:52.105251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:33:51.782569Z","time spent":"322.675728ms","remote":"127.0.0.1:60156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T09:33:52.105282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:33:51.378129Z","time spent":"727.115943ms","remote":"127.0.0.1:60264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:34:33.239681Z","caller":"traceutil/trace.go:171","msg":"trace[630024610] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"203.390297ms","start":"2026-04-20T09:34:33.036268Z","end":"2026-04-20T09:34:33.239659Z","steps":["trace[630024610] 'read index received' (duration: 203.195367ms)","trace[630024610] 'applied index is now lower than readState.Index' (duration: 194.11µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:34:33.23984Z","caller":"traceutil/trace.go:171","msg":"trace[1249405411] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"251.574413ms","start":"2026-04-20T09:34:32.988147Z","end":"2026-04-20T09:34:33.239722Z","steps":["trace[1249405411] 'process raft request' (duration: 251.381843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:34:33.23987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.577737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:34:33.239973Z","caller":"traceutil/trace.go:171","msg":"trace[714919658] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1171; }","duration":"203.756938ms","start":"2026-04-20T09:34:33.036192Z","end":"2026-04-20T09:34:33.239949Z","steps":["trace[714919658] 'agreement among raft nodes before linearized reading' (duration: 203.562699ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:34:53.7922Z","caller":"traceutil/trace.go:171","msg":"trace[1851670216] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"118.945596ms","start":"2026-04-20T09:34:53.673225Z","end":"2026-04-20T09:34:53.792171Z","steps":["trace[1851670216] 'process raft request' (duration: 118.733194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:35:17.35803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.342278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39293bc8-5818-47e1-936f-f3146e9619e0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T09:35:17.358124Z","caller":"traceutil/trace.go:171","msg":"trace[1163495428] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39293bc8-5818-47e1-936f-f3146e9619e0; range_end:; response_count:1; response_revision:1829; }","duration":"143.478339ms","start":"2026-04-20T09:35:17.214622Z","end":"2026-04-20T09:35:17.358101Z","steps":["trace[1163495428] 'range keys from in-memory index tree' (duration: 143.131302ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:35:17.762259Z","caller":"traceutil/trace.go:171","msg":"trace[822318341] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"100.371884ms","start":"2026-04-20T09:35:17.661847Z","end":"2026-04-20T09:35:17.762219Z","steps":["trace[822318341] 'process raft request' (duration: 100.108381ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:36:47.084755Z","caller":"traceutil/trace.go:171","msg":"trace[1472388539] linearizableReadLoop","detail":"{readStateIndex:2553; appliedIndex:2552; }","duration":"173.858915ms","start":"2026-04-20T09:36:46.910861Z","end":"2026-04-20T09:36:47.08472Z","steps":["trace[1472388539] 'read index received' (duration: 173.668751ms)","trace[1472388539] 'applied index is now lower than readState.Index' (duration: 189.244µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:36:47.084838Z","caller":"traceutil/trace.go:171","msg":"trace[618798933] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"212.729214ms","start":"2026-04-20T09:36:46.872062Z","end":"2026-04-20T09:36:47.084791Z","steps":["trace[618798933] 'process raft request' (duration: 212.504682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:36:47.085029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.146794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-20T09:36:47.085077Z","caller":"traceutil/trace.go:171","msg":"trace[1764372823] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2465; }","duration":"174.269039ms","start":"2026-04-20T09:36:46.910794Z","end":"2026-04-20T09:36:47.085063Z","steps":["trace[1764372823] 'agreement among raft nodes before linearized reading' (duration: 174.089526ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:37:20.348582Z","caller":"traceutil/trace.go:171","msg":"trace[1870311546] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"134.410651ms","start":"2026-04-20T09:37:20.214137Z","end":"2026-04-20T09:37:20.348547Z","steps":["trace[1870311546] 'process raft request' (duration: 133.648545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:37:20.42866Z","caller":"traceutil/trace.go:171","msg":"trace[407755] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"210.346918ms","start":"2026-04-20T09:37:20.218288Z","end":"2026-04-20T09:37:20.428635Z","steps":["trace[407755] 'process raft request' (duration: 181.224408ms)","trace[407755] 'compare' (duration: 28.877458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:37:20.429092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.463325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:37:20.429387Z","caller":"traceutil/trace.go:171","msg":"trace[644794422] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2649; }","duration":"169.64438ms","start":"2026-04-20T09:37:20.259515Z","end":"2026-04-20T09:37:20.429159Z","steps":["trace[644794422] 'agreement among raft nodes before linearized reading' (duration: 169.230663ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:37:20.429639Z","caller":"traceutil/trace.go:171","msg":"trace[888323999] linearizableReadLoop","detail":"{readStateIndex:2744; appliedIndex:2742; }","duration":"169.036049ms","start":"2026-04-20T09:37:20.259566Z","end":"2026-04-20T09:37:20.428602Z","steps":["trace[888323999] 'read index received' (duration: 88.267642ms)","trace[888323999] 'applied index is now lower than readState.Index' (duration: 80.767428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:37:25.665212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.831035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T09:37:25.665361Z","caller":"traceutil/trace.go:171","msg":"trace[1026561113] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2677; }","duration":"123.061519ms","start":"2026-04-20T09:37:25.542271Z","end":"2026-04-20T09:37:25.665333Z","steps":["trace[1026561113] 'range keys from in-memory index tree' (duration: 122.529184ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:41:11.689147Z","caller":"traceutil/trace.go:171","msg":"trace[753728275] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"125.128286ms","start":"2026-04-20T09:41:11.564Z","end":"2026-04-20T09:41:11.689128Z","steps":["trace[753728275] 'process raft request' (duration: 124.796332ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:41:11.963341Z","caller":"traceutil/trace.go:171","msg":"trace[527736594] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"270.223573ms","start":"2026-04-20T09:41:11.693068Z","end":"2026-04-20T09:41:11.963292Z","steps":["trace[527736594] 'process raft request' (duration: 240.163089ms)","trace[527736594] 'compare' (duration: 29.587118ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:41:11.968552Z","caller":"traceutil/trace.go:171","msg":"trace[1086985863] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"273.291153ms","start":"2026-04-20T09:41:11.695243Z","end":"2026-04-20T09:41:11.968534Z","steps":["trace[1086985863] 'process raft request' (duration: 273.166012ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:41:11.968795Z","caller":"traceutil/trace.go:171","msg":"trace[19273156] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"272.968489ms","start":"2026-04-20T09:41:11.695799Z","end":"2026-04-20T09:41:11.968767Z","steps":["trace[19273156] 'process raft request' (duration: 272.684826ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:41:11.968999Z","caller":"traceutil/trace.go:171","msg":"trace[781759232] linearizableReadLoop","detail":"{readStateIndex:4822; appliedIndex:4820; }","duration":"273.645277ms","start":"2026-04-20T09:41:11.695333Z","end":"2026-04-20T09:41:11.968978Z","steps":["trace[781759232] 'read index received' (duration: 237.91409ms)","trace[781759232] 'applied index is now lower than readState.Index' (duration: 35.730337ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:41:11.969021Z","caller":"traceutil/trace.go:171","msg":"trace[868654846] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"272.202098ms","start":"2026-04-20T09:41:11.696795Z","end":"2026-04-20T09:41:11.968997Z","steps":["trace[868654846] 'process raft request' (duration: 272.011756ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:41:11.969274Z","caller":"traceutil/trace.go:171","msg":"trace[1503091429] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"273.313413ms","start":"2026-04-20T09:41:11.69595Z","end":"2026-04-20T09:41:11.969263Z","steps":["trace[1503091429] 'process raft request' (duration: 272.739446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:41:11.969377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.993072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-20T09:41:11.971069Z","caller":"traceutil/trace.go:171","msg":"trace[2102318285] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4654; }","duration":"275.724894ms","start":"2026-04-20T09:41:11.695328Z","end":"2026-04-20T09:41:11.971053Z","steps":["trace[2102318285] 'agreement among raft nodes before linearized reading' (duration: 273.870381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:41:11.969607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.227684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-20T09:41:11.97123Z","caller":"traceutil/trace.go:171","msg":"trace[278035968] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4654; }","duration":"102.905645ms","start":"2026-04-20T09:41:11.868315Z","end":"2026-04-20T09:41:11.97122Z","steps":["trace[278035968] 'agreement among raft nodes before linearized reading' (duration: 101.182622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:41:11.969644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.276556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49bf4443-65e8-4c6c-8840-f60aee441bb7\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T09:41:11.971371Z","caller":"traceutil/trace.go:171","msg":"trace[639687375] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49bf4443-65e8-4c6c-8840-f60aee441bb7; range_end:; response_count:1; response_revision:4654; }","duration":"276.052369ms","start":"2026-04-20T09:41:11.695309Z","end":"2026-04-20T09:41:11.971362Z","steps":["trace[639687375] 'agreement among raft nodes before linearized reading' (duration: 274.111393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:41:23.836317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.799792ms","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-20T09:41:23.836387Z","caller":"traceutil/trace.go:171","msg":"trace[1123748275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4813; }","duration":"217.918353ms","start":"2026-04-20T09:41:23.618457Z","end":"2026-04-20T09:41:23.836375Z","steps":["trace[1123748275] 'range keys from in-memory index tree' (duration: 217.66802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:41:23.836666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.971073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-20T09:41:23.836688Z","caller":"traceutil/trace.go:171","msg":"trace[1200744271] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4813; }","duration":"234.041904ms","start":"2026-04-20T09:41:23.60264Z","end":"2026-04-20T09:41:23.836682Z","steps":["trace[1200744271] 'range keys from in-memory index tree' (duration: 233.820672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:41:23.836807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.755927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T09:41:23.836898Z","caller":"traceutil/trace.go:171","msg":"trace[1080613229] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4813; }","duration":"288.95038ms","start":"2026-04-20T09:41:23.547927Z","end":"2026-04-20T09:41:23.836878Z","steps":["trace[1080613229] 'count revisions from in-memory index tree' (duration: 288.533505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:41:23.836885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.813938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T09:41:23.837011Z","caller":"traceutil/trace.go:171","msg":"trace[320586311] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4813; }","duration":"152.99815ms","start":"2026-04-20T09:41:23.68398Z","end":"2026-04-20T09:41:23.836979Z","steps":["trace[320586311] 'range keys from in-memory index tree' (duration: 152.568445ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:41:23.99413Z","caller":"traceutil/trace.go:171","msg":"trace[1462199850] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"106.07001ms","start":"2026-04-20T09:41:23.888016Z","end":"2026-04-20T09:41:23.994086Z","steps":["trace[1462199850] 'process raft request' (duration: 105.887508ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:41:23.994286Z","caller":"traceutil/trace.go:171","msg":"trace[989818132] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"106.10884ms","start":"2026-04-20T09:41:23.888146Z","end":"2026-04-20T09:41:23.994255Z","steps":["trace[989818132] 'process raft request' (duration: 106.032529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:41:23.994242Z","caller":"traceutil/trace.go:171","msg":"trace[1305632374] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"106.05123ms","start":"2026-04-20T09:41:23.888125Z","end":"2026-04-20T09:41:23.994176Z","steps":["trace[1305632374] 'process raft request' (duration: 105.853028ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:16.78182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2945} {"level":"info","ts":"2026-04-20T09:43:16.855251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2945,"took":"71.773173ms","hash":1535701159,"current-db-size-bytes":54923264,"current-db-size":"55 MB","current-db-size-in-use-bytes":30158848,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-20T09:43:16.855338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1535701159,"revision":2945,"compact-revision":-1} {"level":"info","ts":"2026-04-20T09:48:16.786391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5663} {"level":"info","ts":"2026-04-20T09:48:16.861539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5663,"took":"74.103366ms","hash":1717610013,"current-db-size-bytes":54923264,"current-db-size":"55 MB","current-db-size-in-use-bytes":18239488,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T09:48:16.861603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1717610013,"revision":5663,"compact-revision":2945}