{"level":"warn","ts":"2026-04-23T09:45:03.090673Z","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-23T09:45:03.090759Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.143: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.143:2380","--initial-cluster=instance=https://199.19.213.143:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.143:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.143: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-23T09:45:03.090936Z","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-23T09:45:03.090967Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.143:2380"]} {"level":"info","ts":"2026-04-23T09:45:03.090983Z","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-23T09:45:03.091678Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.143:2379"]} {"level":"info","ts":"2026-04-23T09:45:03.091791Z","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.143:2380"],"listen-peer-urls":["https://199.19.213.143:2380"],"advertise-client-urls":["https://199.19.213.143:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.143:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.143: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-23T09:45:03.095954Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.883916ms"} {"level":"info","ts":"2026-04-23T09:45:03.103994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e5bed6df0e44a0b1","cluster-id":"4e2f0a715964d622"} {"level":"info","ts":"2026-04-23T09:45:03.104141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T09:45:03.10417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became follower at term 0"} {"level":"info","ts":"2026-04-23T09:45:03.104237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e5bed6df0e44a0b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T09:45:03.104305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became follower at term 1"} {"level":"info","ts":"2026-04-23T09:45:03.104436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 switched to configuration voters=(16554905533765951665)"} {"level":"warn","ts":"2026-04-23T09:45:03.109462Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T09:45:03.110742Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T09:45:03.111893Z","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-23T09:45:03.113823Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e5bed6df0e44a0b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T09:45:03.114898Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e5bed6df0e44a0b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T09:45:03.115682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 switched to configuration voters=(16554905533765951665)"} {"level":"info","ts":"2026-04-23T09:45:03.115182Z","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-23T09:45:03.115886Z","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-23T09:45:03.115859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4e2f0a715964d622","local-member-id":"e5bed6df0e44a0b1","added-peer-id":"e5bed6df0e44a0b1","added-peer-peer-urls":["https://199.19.213.143:2380"]} {"level":"info","ts":"2026-04-23T09:45:03.1159Z","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-23T09:45:03.123099Z","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-23T09:45:03.123186Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.143:2380"} {"level":"info","ts":"2026-04-23T09:45:03.123231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.143:2380"} {"level":"info","ts":"2026-04-23T09:45:03.123343Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e5bed6df0e44a0b1","initial-advertise-peer-urls":["https://199.19.213.143:2380"],"listen-peer-urls":["https://199.19.213.143:2380"],"advertise-client-urls":["https://199.19.213.143:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.143:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T09:45:03.123378Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T09:45:03.205696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T09:45:03.20578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T09:45:03.20595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 received MsgPreVoteResp from e5bed6df0e44a0b1 at term 1"} {"level":"info","ts":"2026-04-23T09:45:03.205988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became candidate at term 2"} {"level":"info","ts":"2026-04-23T09:45:03.206003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 received MsgVoteResp from e5bed6df0e44a0b1 at term 2"} {"level":"info","ts":"2026-04-23T09:45:03.206022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e5bed6df0e44a0b1 became leader at term 2"} {"level":"info","ts":"2026-04-23T09:45:03.20604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e5bed6df0e44a0b1 elected leader e5bed6df0e44a0b1 at term 2"} {"level":"info","ts":"2026-04-23T09:45:03.262098Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T09:45:03.380668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T09:45:03.380708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T09:45:03.380948Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e5bed6df0e44a0b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.143:2379]}","request-path":"/0/members/e5bed6df0e44a0b1/attributes","cluster-id":"4e2f0a715964d622","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T09:45:03.381337Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T09:45:03.381399Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T09:45:03.381846Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4e2f0a715964d622","local-member-id":"e5bed6df0e44a0b1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T09:45:03.381968Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T09:45:03.382011Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T09:45:03.384184Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T09:45:03.384419Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.143:2379"} {"level":"info","ts":"2026-04-23T09:45:08.7948Z","caller":"traceutil/trace.go:171","msg":"trace[1146664226] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:266; }","duration":"129.948937ms","start":"2026-04-23T09:45:08.664825Z","end":"2026-04-23T09:45:08.794774Z","steps":["trace[1146664226] 'read index received' (duration: 129.770824ms)","trace[1146664226] 'applied index is now lower than readState.Index' (duration: 177.123µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:45:08.794817Z","caller":"traceutil/trace.go:171","msg":"trace[1278679835] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"166.908063ms","start":"2026-04-23T09:45:08.62788Z","end":"2026-04-23T09:45:08.794788Z","steps":["trace[1278679835] 'process raft request' (duration: 166.754001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:45:08.795027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.16656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T09:45:08.795251Z","caller":"traceutil/trace.go:171","msg":"trace[1690580235] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:258; }","duration":"130.428655ms","start":"2026-04-23T09:45:08.6648Z","end":"2026-04-23T09:45:08.795228Z","steps":["trace[1690580235] 'agreement among raft nodes before linearized reading' (duration: 130.08237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:45:09.168045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.056074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T09:45:09.168397Z","caller":"traceutil/trace.go:171","msg":"trace[2000041283] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:267; }","duration":"365.558291ms","start":"2026-04-23T09:45:08.802787Z","end":"2026-04-23T09:45:09.168345Z","steps":["trace[2000041283] 'read index received' (duration: 177.621482ms)","trace[2000041283] 'applied index is now lower than readState.Index' (duration: 187.935499ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:45:09.168519Z","caller":"traceutil/trace.go:171","msg":"trace[1807140539] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"369.294123ms","start":"2026-04-23T09:45:08.79919Z","end":"2026-04-23T09:45:09.168484Z","steps":["trace[1807140539] 'process raft request' (duration: 368.995248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:45:09.168675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.056735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:45:09.168685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.881106ms","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-23T09:45:09.168767Z","caller":"traceutil/trace.go:171","msg":"trace[218583508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:260; }","duration":"187.190146ms","start":"2026-04-23T09:45:08.981552Z","end":"2026-04-23T09:45:09.168742Z","steps":["trace[218583508] 'agreement among raft nodes before linearized reading' (duration: 186.977483ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:09.168772Z","caller":"traceutil/trace.go:171","msg":"trace[429088293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:260; }","duration":"365.990659ms","start":"2026-04-23T09:45:08.802765Z","end":"2026-04-23T09:45:09.168756Z","steps":["trace[429088293] 'agreement among raft nodes before linearized reading' (duration: 365.753185ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:09.168777Z","caller":"traceutil/trace.go:171","msg":"trace[1478417089] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"369.989895ms","start":"2026-04-23T09:45:08.798513Z","end":"2026-04-23T09:45:09.168503Z","steps":["trace[1478417089] 'process raft request' (duration: 181.869393ms)","trace[1478417089] 'compare' (duration: 186.756449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:45:09.168673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:45:08.799175Z","time spent":"369.423315ms","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T09:45:09.168942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:45:08.802749Z","time spent":"366.177221ms","remote":"127.0.0.1:41876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T09:45:09.169225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:45:08.798498Z","time spent":"370.650045ms","remote":"127.0.0.1:41960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T09:45:09.402061Z","caller":"traceutil/trace.go:171","msg":"trace[1926677112] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:269; }","duration":"229.378533ms","start":"2026-04-23T09:45:09.172657Z","end":"2026-04-23T09:45:09.402035Z","steps":["trace[1926677112] 'read index received' (duration: 200.081629ms)","trace[1926677112] 'applied index is now lower than readState.Index' (duration: 29.296084ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:45:09.402143Z","caller":"traceutil/trace.go:171","msg":"trace[1845293621] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"229.994954ms","start":"2026-04-23T09:45:09.172109Z","end":"2026-04-23T09:45:09.402104Z","steps":["trace[1845293621] 'process raft request' (duration: 200.551616ms)","trace[1845293621] 'compare' (duration: 29.262105ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:45:09.402383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.676568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:45:09.402455Z","caller":"traceutil/trace.go:171","msg":"trace[1483895491] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:261; }","duration":"229.819232ms","start":"2026-04-23T09:45:09.172621Z","end":"2026-04-23T09:45:09.40244Z","steps":["trace[1483895491] 'agreement among raft nodes before linearized reading' (duration: 229.534376ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:09.402947Z","caller":"traceutil/trace.go:171","msg":"trace[1684951200] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"229.910982ms","start":"2026-04-23T09:45:09.17302Z","end":"2026-04-23T09:45:09.402931Z","steps":["trace[1684951200] 'process raft request' (duration: 229.706699ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:09.402963Z","caller":"traceutil/trace.go:171","msg":"trace[1104779279] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"110.669455ms","start":"2026-04-23T09:45:09.292271Z","end":"2026-04-23T09:45:09.40294Z","steps":["trace[1104779279] 'process raft request' (duration: 110.616544ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:09.402998Z","caller":"traceutil/trace.go:171","msg":"trace[1500297306] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"229.687019ms","start":"2026-04-23T09:45:09.173277Z","end":"2026-04-23T09:45:09.402964Z","steps":["trace[1500297306] 'process raft request' (duration: 229.554556ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:09.403241Z","caller":"traceutil/trace.go:171","msg":"trace[83322007] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"110.900219ms","start":"2026-04-23T09:45:09.29233Z","end":"2026-04-23T09:45:09.403231Z","steps":["trace[83322007] 'process raft request' (duration: 110.770167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:45:09.403303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.003454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-23T09:45:09.40336Z","caller":"traceutil/trace.go:171","msg":"trace[938859187] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"111.079462ms","start":"2026-04-23T09:45:09.292271Z","end":"2026-04-23T09:45:09.403351Z","steps":["trace[938859187] 'process raft request' (duration: 110.649025ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:09.40337Z","caller":"traceutil/trace.go:171","msg":"trace[1976185106] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:263; }","duration":"110.110465ms","start":"2026-04-23T09:45:09.293238Z","end":"2026-04-23T09:45:09.403348Z","steps":["trace[1976185106] 'agreement among raft nodes before linearized reading' (duration: 109.969973ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:09.403368Z","caller":"traceutil/trace.go:171","msg":"trace[812267239] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"109.20096ms","start":"2026-04-23T09:45:09.294155Z","end":"2026-04-23T09:45:09.403356Z","steps":["trace[812267239] 'process raft request' (duration: 109.017127ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:27.931387Z","caller":"traceutil/trace.go:171","msg":"trace[1902268723] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"130.528177ms","start":"2026-04-23T09:45:27.800836Z","end":"2026-04-23T09:45:27.931364Z","steps":["trace[1902268723] 'process raft request' (duration: 130.244103ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:37.62351Z","caller":"traceutil/trace.go:171","msg":"trace[57416384] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"243.839916ms","start":"2026-04-23T09:45:37.379654Z","end":"2026-04-23T09:45:37.623494Z","steps":["trace[57416384] 'process raft request' (duration: 243.52287ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:38.138397Z","caller":"traceutil/trace.go:171","msg":"trace[48712627] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"426.03232ms","start":"2026-04-23T09:45:37.712338Z","end":"2026-04-23T09:45:38.13837Z","steps":["trace[48712627] 'read index received' (duration: 424.993585ms)","trace[48712627] 'applied index is now lower than readState.Index' (duration: 1.037655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:45:38.138619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.269684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:45:38.138713Z","caller":"traceutil/trace.go:171","msg":"trace[175764391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"426.441997ms","start":"2026-04-23T09:45:37.712249Z","end":"2026-04-23T09:45:38.138691Z","steps":["trace[175764391] 'agreement among raft nodes before linearized reading' (duration: 426.281624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:45:38.138653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.429852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T09:45:38.138758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:45:37.712214Z","time spent":"426.535998ms","remote":"127.0.0.1:41612","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-23T09:45:38.138802Z","caller":"traceutil/trace.go:171","msg":"trace[1516656082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"103.637087ms","start":"2026-04-23T09:45:38.03515Z","end":"2026-04-23T09:45:38.138787Z","steps":["trace[1516656082] 'agreement among raft nodes before linearized reading' (duration: 103.369252ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:45:38.138662Z","caller":"traceutil/trace.go:171","msg":"trace[2094040101] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"479.884531ms","start":"2026-04-23T09:45:37.65869Z","end":"2026-04-23T09:45:38.138575Z","steps":["trace[2094040101] 'process raft request' (duration: 478.781375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:45:38.139469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:45:37.658666Z","time spent":"480.41968ms","remote":"127.0.0.1:41706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:45:38.262181Z","caller":"traceutil/trace.go:171","msg":"trace[2106187764] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"119.412945ms","start":"2026-04-23T09:45:38.14273Z","end":"2026-04-23T09:45:38.262143Z","steps":["trace[2106187764] 'process raft request' (duration: 97.140144ms)","trace[2106187764] 'compare' (duration: 22.056817ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:45:52.81968Z","caller":"traceutil/trace.go:171","msg":"trace[1789268751] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"108.512259ms","start":"2026-04-23T09:45:52.711135Z","end":"2026-04-23T09:45:52.819647Z","steps":["trace[1789268751] 'read index received' (duration: 108.244745ms)","trace[1789268751] 'applied index is now lower than readState.Index' (duration: 266.224µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:45:52.819763Z","caller":"traceutil/trace.go:171","msg":"trace[693269280] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"175.04192ms","start":"2026-04-23T09:45:52.644691Z","end":"2026-04-23T09:45:52.819733Z","steps":["trace[693269280] 'process raft request' (duration: 174.776516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:45:52.820133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.759002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:45:52.820228Z","caller":"traceutil/trace.go:171","msg":"trace[1209031366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"109.113446ms","start":"2026-04-23T09:45:52.711096Z","end":"2026-04-23T09:45:52.820209Z","steps":["trace[1209031366] 'agreement among raft nodes before linearized reading' (duration: 108.731011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:47:02.94166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.50766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-04-23T09:47:02.941755Z","caller":"traceutil/trace.go:171","msg":"trace[1024593824] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1146; }","duration":"129.649322ms","start":"2026-04-23T09:47:02.812087Z","end":"2026-04-23T09:47:02.941736Z","steps":["trace[1024593824] 'range keys from in-memory index tree' (duration: 129.368047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:47:02.942004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.71358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"warn","ts":"2026-04-23T09:47:02.942041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.18274ms","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-23T09:47:02.942084Z","caller":"traceutil/trace.go:171","msg":"trace[1807261347] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1146; }","duration":"122.829721ms","start":"2026-04-23T09:47:02.819237Z","end":"2026-04-23T09:47:02.942066Z","steps":["trace[1807261347] 'range keys from in-memory index tree' (duration: 122.241044ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:47:02.942156Z","caller":"traceutil/trace.go:171","msg":"trace[54549332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1146; }","duration":"172.328452ms","start":"2026-04-23T09:47:02.769791Z","end":"2026-04-23T09:47:02.94212Z","steps":["trace[54549332] 'range keys from in-memory index tree' (duration: 171.877137ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:47:06.424945Z","caller":"traceutil/trace.go:171","msg":"trace[319807631] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"223.441949ms","start":"2026-04-23T09:47:06.201478Z","end":"2026-04-23T09:47:06.42492Z","steps":["trace[319807631] 'read index received' (duration: 222.998594ms)","trace[319807631] 'applied index is now lower than readState.Index' (duration: 442.215µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:47:06.424987Z","caller":"traceutil/trace.go:171","msg":"trace[1330838416] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"244.264371ms","start":"2026-04-23T09:47:06.180692Z","end":"2026-04-23T09:47:06.424957Z","steps":["trace[1330838416] 'process raft request' (duration: 243.797606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:47:06.425128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.34705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:661"} {"level":"info","ts":"2026-04-23T09:47:06.425166Z","caller":"traceutil/trace.go:171","msg":"trace[1546187515] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1157; }","duration":"171.423631ms","start":"2026-04-23T09:47:06.253732Z","end":"2026-04-23T09:47:06.425155Z","steps":["trace[1546187515] 'agreement among raft nodes before linearized reading' (duration: 171.33378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:47:06.42518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.693611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-23T09:47:06.425231Z","caller":"traceutil/trace.go:171","msg":"trace[1433100612] range","detail":"{range_begin:/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1; range_end:; response_count:1; response_revision:1157; }","duration":"223.791173ms","start":"2026-04-23T09:47:06.201425Z","end":"2026-04-23T09:47:06.425216Z","steps":["trace[1433100612] 'agreement among raft nodes before linearized reading' (duration: 223.629071ms)"],"step_count":1}